builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-072 starttime: 1487823688.26 results: success (0) revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.264780) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.265148) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.265431) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.293799) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.294039) ========= 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=199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 20:21:29-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2017-02-22 20:21:29 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117991 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.424877) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.425165) ========= 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=199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022689 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.474835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:28.475186) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --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=199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 20:21:29,235 truncating revision to first 12 chars 2017-02-22 20:21:29,236 Setting DEBUG logging. 2017-02-22 20:21:29,236 attempt 1/10 2017-02-22 20:21:29,236 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c7b015c488cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-22 20:21:30,073 unpacking tar archive at: mozilla-central-c7b015c488cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.145716 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:29.633300) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.633592) ========= script_repo_revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.633944) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.634206) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:29.642016) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-22 20:21:29.642293) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:21:30 INFO - MultiFileLogger online at 20170222 20:21:30 in /builds/slave/test 20:21:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 20:21:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:21:30 INFO - {'append_to_log': False, 20:21:30 INFO - 'base_work_dir': '/builds/slave/test', 20:21:30 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:21:30 INFO - 'branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'buildbot_json_path': 'buildprops.json', 20:21:30 INFO - 'config_files': ('talos/linux_config.py',), 20:21:30 INFO - 'default_actions': ('clobber', 20:21:30 INFO - 'read-buildbot-config', 20:21:30 INFO - 'download-and-extract', 20:21:30 INFO - 'populate-webroot', 20:21:30 INFO - 'create-virtualenv', 20:21:30 INFO - 'install', 20:21:30 INFO - 'run-tests'), 20:21:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:21:30 INFO - 'download_minidump_stackwalk': True, 20:21:30 INFO - 'download_symbols': 'ondemand', 20:21:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:21:30 INFO - 'tooltool.py': '/tools/tooltool.py', 20:21:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:21:30 INFO - '/tools/misc-python/virtualenv.py')}, 20:21:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:21:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:21:30 INFO - 'gecko_profile': False, 20:21:30 INFO - 'gecko_profile_interval': 0, 20:21:30 INFO - 'installer_path': 'installer.exe', 20:21:30 INFO - 'log_level': 'info', 20:21:30 INFO - 'log_name': 'talos', 20:21:30 INFO - 'log_to_console': True, 20:21:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:21:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:21:30 INFO - 'opt_config_files': (), 20:21:30 INFO - 'pip_index': False, 20:21:30 INFO - 'suite': 'dromaeojs-e10s', 20:21:30 INFO - 'system_bits': '32', 20:21:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:21:30 INFO - 'title': 'talos-linux64-ix-072', 20:21:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:21:30 INFO - 'use_talos_json': True, 20:21:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:21:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:21:30 INFO - 'work_dir': 'build'} 20:21:30 INFO - [mozharness: 2017-02-23 04:21:30.507503Z] Running clobber step. 20:21:30 INFO - Running pre-action listener: _resource_record_pre_action 20:21:30 INFO - Running main action method: clobber 20:21:30 INFO - rmtree: /builds/slave/test/build 20:21:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:21:31 INFO - Running post-action listener: _resource_record_post_action 20:21:31 INFO - [mozharness: 2017-02-23 04:21:31.947807Z] Finished clobber step (success) 20:21:31 INFO - [mozharness: 2017-02-23 04:21:31.947905Z] Running read-buildbot-config step. 20:21:31 INFO - Running pre-action listener: _resource_record_pre_action 20:21:31 INFO - Running main action method: read_buildbot_config 20:21:31 INFO - Using buildbot properties: 20:21:31 INFO - { 20:21:31 INFO - "project": "", 20:21:31 INFO - "product": "firefox", 20:21:31 INFO - "who": "kwierso@gmail.com", 20:21:31 INFO - "installer_path": "public/build/target.tar.bz2", 20:21:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:21:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:21:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:21:31 INFO - "stage_platform": "linux64", 20:21:31 INFO - "basedir": "/builds/slave/test", 20:21:31 INFO - "buildnumber": 692, 20:21:31 INFO - "platform": "ubuntu64_hw", 20:21:31 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:21:31 INFO - "slavebuilddir": "test", 20:21:31 INFO - "taskId": "GrqYadKmTFeg40rrWXdsDg", 20:21:31 INFO - "branch": "mozilla-central", 20:21:31 INFO - "script_repo_revision": "production", 20:21:31 INFO - "revision": "c7b015c488cfb2afbcff295a9639acd85df332f8", 20:21:31 INFO - "slavename": "talos-linux64-ix-072", 20:21:31 INFO - "repo_path": "mozilla-central" 20:21:31 INFO - } 20:21:31 INFO - Finding installer, test and symbols from parent task. 20:21:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:32 INFO - Task dependencies: fziZoBLZQf6J64GfmjMy5g 20:21:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g'}, attempt #1 20:21:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:21:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/test_packages.json 20:21:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip 20:21:33 INFO - Running post-action listener: _resource_record_post_action 20:21:33 INFO - [mozharness: 2017-02-23 04:21:33.458139Z] Finished read-buildbot-config step (success) 20:21:33 INFO - [mozharness: 2017-02-23 04:21:33.458355Z] Running download-and-extract step. 20:21:33 INFO - Running pre-action listener: _resource_record_pre_action 20:21:33 INFO - Running main action method: download_and_extract 20:21:33 INFO - mkdir: /builds/slave/test/build/tests 20:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:21:33 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json 20:21:33 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:21:34 INFO - Downloaded 896 bytes. 20:21:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:21:34 INFO - Using the following test package requirements: 20:21:34 INFO - {u'common': [u'target.common.tests.zip'], 20:21:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:21:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:21:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:21:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:21:34 INFO - u'mozbase': [u'target.common.tests.zip'], 20:21:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:21:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:21:34 INFO - u'web-platform': [u'target.common.tests.zip', 20:21:34 INFO - u'target.web-platform.tests.zip'], 20:21:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:21:34 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:21:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:21:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:21:34 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:38 INFO - Expected file size: 57165889 20:21:38 INFO - Obtained file size: 57165889 20:21:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:21:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:21:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:21:47 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:49 INFO - Expected file size: 57165889 20:21:49 INFO - Obtained file size: 57165889 20:22:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip 20:22:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:22:00 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip into memory 20:22:01 INFO - Expected file size: 14404319 20:22:01 INFO - Obtained file size: 14404319 20:22:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:01 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:01 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:22:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:22:03 INFO - Downloaded 61547669 bytes. 20:22:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:03 INFO - mkdir: /builds/slave/test/properties 20:22:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:22:03 INFO - Writing to file /builds/slave/test/properties/build_url 20:22:03 INFO - Contents: 20:22:03 INFO - build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:03 INFO - Running post-action listener: _resource_record_post_action 20:22:03 INFO - Running post-action listener: set_extra_try_arguments 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.345341Z] Finished download-and-extract step (success) 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.345438Z] Running populate-webroot step. 20:22:03 INFO - Running pre-action listener: _resource_record_pre_action 20:22:03 INFO - Running main action method: populate_webroot 20:22:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:22:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:22:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:22:03 INFO - '--tppagecycles', 20:22:03 INFO - '7'], 20:22:03 INFO - 'tests': ['tsvgm']}}, 20:22:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:22:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:22:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:22:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:22:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['damp', 'tps']}, 20:22:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'tests': ['damp', 'tps']}, 20:22:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['dromaeo_dom']}, 20:22:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:22:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['basic_compositor_video']}, 20:22:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:22:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other-e10s': {'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tsvgx', 20:22:03 INFO - 'tsvgr_opacity', 20:22:03 INFO - 'tart', 20:22:03 INFO - 'tscrollx', 20:22:03 INFO - 'cart', 20:22:03 INFO - 'tsvg_static']}, 20:22:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:22:03 INFO - 'tsvgr_opacity', 20:22:03 INFO - 'tart', 20:22:03 INFO - 'tscrollx', 20:22:03 INFO - 'cart', 20:22:03 INFO - 'tsvg_static']}, 20:22:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tp5o']}, 20:22:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:22:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s', 20:22:03 INFO - '--xperf_path', 20:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:03 INFO - 'tests': ['tp5n']}, 20:22:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--xperf_path', 20:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:03 INFO - 'tests': ['tp5n']}}, 20:22:03 INFO - 'talos.zip': {'path': '', 20:22:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:22:03 INFO - Running post-action listener: _resource_record_post_action 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.358521Z] Finished populate-webroot step (success) 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.358611Z] Running create-virtualenv step. 20:22:03 INFO - Running pre-action listener: _resource_record_pre_action 20:22:03 INFO - Running main action method: create_virtualenv 20:22:03 INFO - Creating virtualenv /builds/slave/test/build/venv 20:22:03 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 20:22:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:22:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:22:03 INFO - Using real prefix '/usr' 20:22:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:22:03 INFO - Installing distribute.............................................................................................................................................................................................done. 20:22:05 INFO - Installing pip.................done. 20:22:05 INFO - Return code: 0 20:22:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:22:06 INFO - Reading from file tmpfile_stdout 20:22:06 INFO - Output received: 20:22:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:22:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:22:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 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 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:06 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 20:22:06 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 20:22:06 INFO - Using env: {'DISPLAY': ':0', 20:22:06 INFO - 'HOME': '/home/cltbld', 20:22:06 INFO - 'LANG': 'en_US.UTF-8', 20:22:06 INFO - 'LANGUAGE': 'en_US:en', 20:22:06 INFO - 'LOGNAME': 'cltbld', 20:22:06 INFO - 'MAIL': '/var/mail/cltbld', 20:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:06 INFO - 'MOZ_NO_REMOTE': '1', 20:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:06 INFO - 'NO_EM_RESTART': '1', 20:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:06 INFO - 'PWD': '/builds/slave/test', 20:22:06 INFO - 'SHELL': '/bin/bash', 20:22:06 INFO - 'SHLVL': '1', 20:22:06 INFO - 'TERM': 'linux', 20:22:06 INFO - 'TMOUT': '86400', 20:22:06 INFO - 'USER': 'cltbld', 20:22:06 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:06 INFO - '_': '/tools/buildbot/bin/python'} 20:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:06 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 20:22:06 INFO - Cleaning up... 20:22:06 INFO - Return code: 0 20:22:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:22:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:22:06 INFO - Using env: {'DISPLAY': ':0', 20:22:06 INFO - 'HOME': '/home/cltbld', 20:22:06 INFO - 'LANG': 'en_US.UTF-8', 20:22:06 INFO - 'LANGUAGE': 'en_US:en', 20:22:06 INFO - 'LOGNAME': 'cltbld', 20:22:06 INFO - 'MAIL': '/var/mail/cltbld', 20:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:06 INFO - 'MOZ_NO_REMOTE': '1', 20:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:06 INFO - 'NO_EM_RESTART': '1', 20:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:06 INFO - 'PWD': '/builds/slave/test', 20:22:06 INFO - 'SHELL': '/bin/bash', 20:22:06 INFO - 'SHLVL': '1', 20:22:06 INFO - 'TERM': 'linux', 20:22:06 INFO - 'TMOUT': '86400', 20:22:06 INFO - 'USER': 'cltbld', 20:22:06 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:06 INFO - '_': '/tools/buildbot/bin/python'} 20:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:06 INFO - Downloading/unpacking psutil>=3.1.1 20:22:06 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 20:22:06 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 20:22:06 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 20:22:06 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 20:22:06 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 20:22:06 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 20:22:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:22:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:09 INFO - Installing collected packages: psutil 20:22:09 INFO - Running setup.py install for psutil 20:22:09 INFO - building 'psutil._psutil_linux' extension 20:22:09 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 20:22:09 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 20:22:09 INFO - building 'psutil._psutil_posix' extension 20:22:09 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 20:22:09 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 20:22:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:09 INFO - Successfully installed psutil 20:22:09 INFO - Cleaning up... 20:22:09 INFO - Return code: 0 20:22:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:22:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:22:09 INFO - Using env: {'DISPLAY': ':0', 20:22:09 INFO - 'HOME': '/home/cltbld', 20:22:09 INFO - 'LANG': 'en_US.UTF-8', 20:22:09 INFO - 'LANGUAGE': 'en_US:en', 20:22:09 INFO - 'LOGNAME': 'cltbld', 20:22:09 INFO - 'MAIL': '/var/mail/cltbld', 20:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:09 INFO - 'MOZ_NO_REMOTE': '1', 20:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:09 INFO - 'NO_EM_RESTART': '1', 20:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:09 INFO - 'PWD': '/builds/slave/test', 20:22:09 INFO - 'SHELL': '/bin/bash', 20:22:09 INFO - 'SHLVL': '1', 20:22:09 INFO - 'TERM': 'linux', 20:22:09 INFO - 'TMOUT': '86400', 20:22:09 INFO - 'USER': 'cltbld', 20:22:09 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:09 INFO - '_': '/tools/buildbot/bin/python'} 20:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:22:11 INFO - Installing collected packages: mozsystemmonitor 20:22:11 INFO - Running setup.py install for mozsystemmonitor 20:22:12 INFO - Successfully installed mozsystemmonitor 20:22:12 INFO - Cleaning up... 20:22:12 INFO - Return code: 0 20:22:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:22:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:22:12 INFO - Using env: {'DISPLAY': ':0', 20:22:12 INFO - 'HOME': '/home/cltbld', 20:22:12 INFO - 'LANG': 'en_US.UTF-8', 20:22:12 INFO - 'LANGUAGE': 'en_US:en', 20:22:12 INFO - 'LOGNAME': 'cltbld', 20:22:12 INFO - 'MAIL': '/var/mail/cltbld', 20:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:12 INFO - 'MOZ_NO_REMOTE': '1', 20:22:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:12 INFO - 'NO_EM_RESTART': '1', 20:22:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:12 INFO - 'PWD': '/builds/slave/test', 20:22:12 INFO - 'SHELL': '/bin/bash', 20:22:12 INFO - 'SHLVL': '1', 20:22:12 INFO - 'TERM': 'linux', 20:22:12 INFO - 'TMOUT': '86400', 20:22:12 INFO - 'USER': 'cltbld', 20:22:12 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:12 INFO - '_': '/tools/buildbot/bin/python'} 20:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:12 INFO - Downloading/unpacking jsonschema==2.5.1 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22: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 20:22:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:22:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:22:14 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 20:22:14 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 20:22:14 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 20:22:14 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 20:22:14 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 20:22:14 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 20:22:14 INFO - Downloading functools32-3.2.3-2.tar.gz 20:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:22:14 INFO - warning: no files found matching '*.txt' 20:22:14 INFO - no previously-included directories found matching 'build' 20:22:14 INFO - no previously-included directories found matching 'dist' 20:22:14 INFO - no previously-included directories found matching '.git*' 20:22:14 INFO - Installing collected packages: jsonschema, functools32 20:22:14 INFO - Running setup.py install for functools32 20:22:14 INFO - warning: no files found matching '*.txt' 20:22:14 INFO - no previously-included directories found matching 'build' 20:22:14 INFO - no previously-included directories found matching 'dist' 20:22:14 INFO - no previously-included directories found matching '.git*' 20:22:14 INFO - Successfully installed jsonschema functools32 20:22:14 INFO - Cleaning up... 20:22:14 INFO - Return code: 0 20:22:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:14 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:22:14 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 functools32==3.2.3-2 20:22:14 INFO - Using env: {'DISPLAY': ':0', 20:22:14 INFO - 'HOME': '/home/cltbld', 20:22:14 INFO - 'LANG': 'en_US.UTF-8', 20:22:14 INFO - 'LANGUAGE': 'en_US:en', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MAIL': '/var/mail/cltbld', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SHLVL': '1', 20:22:14 INFO - 'TERM': 'linux', 20:22:14 INFO - 'TMOUT': '86400', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - '_': '/tools/buildbot/bin/python'} 20:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:22:15 INFO - Cleaning up... 20:22:15 INFO - Return code: 0 20:22:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:15 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 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:15 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 20:22:15 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 20:22:15 INFO - Using env: {'DISPLAY': ':0', 20:22:15 INFO - 'HOME': '/home/cltbld', 20:22:15 INFO - 'LANG': 'en_US.UTF-8', 20:22:15 INFO - 'LANGUAGE': 'en_US:en', 20:22:15 INFO - 'LOGNAME': 'cltbld', 20:22:15 INFO - 'MAIL': '/var/mail/cltbld', 20:22:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:15 INFO - 'MOZ_NO_REMOTE': '1', 20:22:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:15 INFO - 'NO_EM_RESTART': '1', 20:22:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:15 INFO - 'PWD': '/builds/slave/test', 20:22:15 INFO - 'SHELL': '/bin/bash', 20:22:15 INFO - 'SHLVL': '1', 20:22:15 INFO - 'TERM': 'linux', 20:22:15 INFO - 'TMOUT': '86400', 20:22:15 INFO - 'USER': 'cltbld', 20:22:15 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:15 INFO - '_': '/tools/buildbot/bin/python'} 20:22:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:15 INFO - Downloading/unpacking blobuploader==1.2.4 20:22:15 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 20:22:15 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 20:22:15 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 20:22:15 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 20:22:15 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 20:22:15 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 20:22:17 INFO - Downloading blobuploader-1.2.4.tar.gz 20:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:22:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:22:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 INFO - Downloading docopt-0.6.1.tar.gz 20:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:22:18 INFO - Installing collected packages: blobuploader, requests, docopt 20:22:18 INFO - Running setup.py install for blobuploader 20:22:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:22:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:22:18 INFO - Running setup.py install for requests 20:22:18 INFO - Running setup.py install for docopt 20:22:18 INFO - Successfully installed blobuploader requests docopt 20:22:18 INFO - Cleaning up... 20:22:18 INFO - Return code: 0 20:22:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:18 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 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:18 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 20:22:18 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 20:22:18 INFO - Using env: {'DISPLAY': ':0', 20:22:18 INFO - 'HOME': '/home/cltbld', 20:22:18 INFO - 'LANG': 'en_US.UTF-8', 20:22:18 INFO - 'LANGUAGE': 'en_US:en', 20:22:18 INFO - 'LOGNAME': 'cltbld', 20:22:18 INFO - 'MAIL': '/var/mail/cltbld', 20:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:18 INFO - 'MOZ_NO_REMOTE': '1', 20:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:18 INFO - 'NO_EM_RESTART': '1', 20:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:18 INFO - 'PWD': '/builds/slave/test', 20:22:18 INFO - 'SHELL': '/bin/bash', 20:22:18 INFO - 'SHLVL': '1', 20:22:18 INFO - 'TERM': 'linux', 20:22:18 INFO - 'TMOUT': '86400', 20:22:18 INFO - 'USER': 'cltbld', 20:22:18 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:18 INFO - '_': '/tools/buildbot/bin/python'} 20:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 INFO - Running setup.py (path:/tmp/pip-cPXguA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:18 INFO - Running setup.py (path:/tmp/pip-3rrP2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:19 INFO - Running setup.py (path:/tmp/pip-_52Fej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:19 INFO - Running setup.py (path:/tmp/pip-P3a6on-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:19 INFO - Running setup.py (path:/tmp/pip-AOUwd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:19 INFO - Running setup.py (path:/tmp/pip-ktBhWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:19 INFO - Running setup.py (path:/tmp/pip-qi5JCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:19 INFO - Running setup.py (path:/tmp/pip-21Qg2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:19 INFO - Running setup.py (path:/tmp/pip-5BMu47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:19 INFO - Running setup.py (path:/tmp/pip-XSi1Np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:19 INFO - Running setup.py (path:/tmp/pip-gsKLeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:19 INFO - Running setup.py (path:/tmp/pip-6rp3a4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 INFO - Running setup.py (path:/tmp/pip-n7Gvck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 INFO - Running setup.py (path:/tmp/pip-ySekyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 INFO - Running setup.py (path:/tmp/pip-qeZ1pL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:19 INFO - Running setup.py (path:/tmp/pip-Yn4Ywj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:20 INFO - Running setup.py (path:/tmp/pip-IiP6PT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:22:20 INFO - Running setup.py install for manifestparser 20:22:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozcrash 20:22:20 INFO - Running setup.py install for mozdebug 20:22:20 INFO - Running setup.py install for mozdevice 20:22:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozfile 20:22:20 INFO - Running setup.py install for mozhttpd 20:22:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozinfo 20:22:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozInstall 20:22:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozleak 20:22:21 INFO - Running setup.py install for mozlog 20:22:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Running setup.py install for moznetwork 20:22:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Running setup.py install for mozprocess 20:22:21 INFO - Running setup.py install for mozprofile 20:22:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Running setup.py install for mozrunner 20:22:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Running setup.py install for mozscreenshot 20:22:21 INFO - Running setup.py install for moztest 20:22:21 INFO - Running setup.py install for mozversion 20:22:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:22:21 INFO - Cleaning up... 20:22:21 INFO - Return code: 0 20:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:21 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 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:21 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 20:22:21 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 20:22:21 INFO - Using env: {'DISPLAY': ':0', 20:22:21 INFO - 'HOME': '/home/cltbld', 20:22:21 INFO - 'LANG': 'en_US.UTF-8', 20:22:21 INFO - 'LANGUAGE': 'en_US:en', 20:22:21 INFO - 'LOGNAME': 'cltbld', 20:22:21 INFO - 'MAIL': '/var/mail/cltbld', 20:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:21 INFO - 'MOZ_NO_REMOTE': '1', 20:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:21 INFO - 'NO_EM_RESTART': '1', 20:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:21 INFO - 'PWD': '/builds/slave/test', 20:22:21 INFO - 'SHELL': '/bin/bash', 20:22:21 INFO - 'SHLVL': '1', 20:22:21 INFO - 'TERM': 'linux', 20:22:21 INFO - 'TMOUT': '86400', 20:22:21 INFO - 'USER': 'cltbld', 20:22:21 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:21 INFO - '_': '/tools/buildbot/bin/python'} 20:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:22 INFO - Running setup.py (path:/tmp/pip-Mq9FJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:22 INFO - Running setup.py (path:/tmp/pip-mZwlJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:22 INFO - Running setup.py (path:/tmp/pip-0pu5A7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:22 INFO - Running setup.py (path:/tmp/pip-7KL3B2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:22 INFO - Running setup.py (path:/tmp/pip-3QNtww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:22 INFO - Running setup.py (path:/tmp/pip-IwRAQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:22 INFO - Running setup.py (path:/tmp/pip-ld0hnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:22 INFO - Running setup.py (path:/tmp/pip-465Aps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:22 INFO - Running setup.py (path:/tmp/pip-5MajZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:22 INFO - Running setup.py (path:/tmp/pip-hY3oji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:22 INFO - Running setup.py (path:/tmp/pip-OPRpRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:22 INFO - Running setup.py (path:/tmp/pip-LBnSrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:23 INFO - Running setup.py (path:/tmp/pip-V_5kMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:23 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)) 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:23 INFO - Running setup.py (path:/tmp/pip-Gv4HEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:23 INFO - Running setup.py (path:/tmp/pip-xzwWlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:23 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)) 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:23 INFO - Running setup.py (path:/tmp/pip-QS_38j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:23 INFO - Running setup.py (path:/tmp/pip-V5Zwll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:23 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)) 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:22:23 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)) 20:22:23 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:22:23 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:22:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:23 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 20:22:23 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 20:22:23 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 20:22:23 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 20:22:23 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 20:22:23 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 20:22:25 INFO - Downloading blessings-1.6.tar.gz 20:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:22:25 INFO - Installing collected packages: blessings 20:22:25 INFO - Running setup.py install for blessings 20:22:25 INFO - Successfully installed blessings 20:22:25 INFO - Cleaning up... 20:22:25 INFO - Return code: 0 20:22:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:25 INFO - Reading from file tmpfile_stdout 20:22:25 INFO - Current package versions: 20:22:25 INFO - argparse == 1.2.1 20:22:25 INFO - blessings == 1.6 20:22:25 INFO - blobuploader == 1.2.4 20:22:25 INFO - docopt == 0.6.1 20:22:25 INFO - functools32 == 3.2.3-2 20:22:25 INFO - jsonschema == 2.5.1 20:22:25 INFO - manifestparser == 1.1 20:22:25 INFO - mozInstall == 1.12 20:22:25 INFO - mozcrash == 1.0 20:22:25 INFO - mozdebug == 0.1 20:22:25 INFO - mozdevice == 0.49 20:22:25 INFO - mozfile == 1.2 20:22:25 INFO - mozhttpd == 0.7 20:22:25 INFO - mozinfo == 0.9 20:22:25 INFO - mozleak == 0.1 20:22:25 INFO - mozlog == 3.4 20:22:25 INFO - moznetwork == 0.27 20:22:25 INFO - mozprocess == 0.25 20:22:25 INFO - mozprofile == 0.28 20:22:25 INFO - mozrunner == 6.13 20:22:25 INFO - mozscreenshot == 0.1 20:22:25 INFO - mozsystemmonitor == 0.3 20:22:25 INFO - moztest == 0.8 20:22:25 INFO - mozversion == 1.4 20:22:25 INFO - psutil == 3.1.1 20:22:25 INFO - requests == 1.2.3 20:22:25 INFO - wsgiref == 0.1.2 20:22:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 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 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:25 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 20:22:25 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 20:22:25 INFO - Using env: {'DISPLAY': ':0', 20:22:25 INFO - 'HOME': '/home/cltbld', 20:22:25 INFO - 'LANG': 'en_US.UTF-8', 20:22:25 INFO - 'LANGUAGE': 'en_US:en', 20:22:25 INFO - 'LOGNAME': 'cltbld', 20:22:25 INFO - 'MAIL': '/var/mail/cltbld', 20:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:25 INFO - 'MOZ_NO_REMOTE': '1', 20:22:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:25 INFO - 'NO_EM_RESTART': '1', 20:22:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:25 INFO - 'PWD': '/builds/slave/test', 20:22:25 INFO - 'SHELL': '/bin/bash', 20:22:25 INFO - 'SHLVL': '1', 20:22:25 INFO - 'TERM': 'linux', 20:22:25 INFO - 'TMOUT': '86400', 20:22:25 INFO - 'USER': 'cltbld', 20:22:25 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:25 INFO - '_': '/tools/buildbot/bin/python'} 20:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:26 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)) 20:22:26 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)) 20:22:26 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)) 20:22:26 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)) 20:22:26 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)) 20:22:26 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)) 20:22:26 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)) 20:22:26 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)) 20:22:26 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)) 20:22:26 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)) 20:22:26 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)) 20:22:26 INFO - Cleaning up... 20:22:26 INFO - Return code: 0 20:22:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:22:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:26 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 0x25631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, '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': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', '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 20:22:26 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 20:22:26 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 20:22:26 INFO - Using env: {'DISPLAY': ':0', 20:22:26 INFO - 'HOME': '/home/cltbld', 20:22:26 INFO - 'LANG': 'en_US.UTF-8', 20:22:26 INFO - 'LANGUAGE': 'en_US:en', 20:22:26 INFO - 'LOGNAME': 'cltbld', 20:22:26 INFO - 'MAIL': '/var/mail/cltbld', 20:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:26 INFO - 'MOZ_NO_REMOTE': '1', 20:22:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:26 INFO - 'NO_EM_RESTART': '1', 20:22:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:26 INFO - 'PWD': '/builds/slave/test', 20:22:26 INFO - 'SHELL': '/bin/bash', 20:22:26 INFO - 'SHLVL': '1', 20:22:26 INFO - 'TERM': 'linux', 20:22:26 INFO - 'TMOUT': '86400', 20:22:26 INFO - 'USER': 'cltbld', 20:22:26 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:26 INFO - '_': '/tools/buildbot/bin/python'} 20:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:22:26 INFO - Cleaning up... 20:22:26 INFO - Return code: 0 20:22:26 INFO - Running post-action listener: _resource_record_post_action 20:22:26 INFO - Running post-action listener: _start_resource_monitoring 20:22:26 INFO - Starting resource monitoring. 20:22:26 INFO - [mozharness: 2017-02-23 04:22:26.377052Z] Finished create-virtualenv step (success) 20:22:26 INFO - [mozharness: 2017-02-23 04:22:26.377526Z] Running install step. 20:22:26 INFO - Running pre-action listener: _resource_record_pre_action 20:22:26 INFO - Running main action method: install 20:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:26 INFO - Reading from file tmpfile_stdout 20:22:26 INFO - Detecting whether we're running mozinstall >=1.0... 20:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:22:26 INFO - Reading from file tmpfile_stdout 20:22:26 INFO - Output received: 20:22:26 INFO - Usage: mozinstall [options] installer 20:22:26 INFO - Options: 20:22:26 INFO - -h, --help show this help message and exit 20:22:26 INFO - -d DEST, --destination=DEST 20:22:26 INFO - Directory to install application into. [default: 20:22:26 INFO - "/builds/slave/test"] 20:22:26 INFO - --app=APP Application being installed. [default: firefox] 20:22:26 INFO - mkdir: /builds/slave/test/build/application 20:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:22:42 INFO - Reading from file tmpfile_stdout 20:22:42 INFO - Output received: 20:22:42 INFO - /builds/slave/test/build/application/firefox/firefox 20:22:42 INFO - Running post-action listener: _resource_record_post_action 20:22:42 INFO - [mozharness: 2017-02-23 04:22:42.130757Z] Finished install step (success) 20:22:42 INFO - [mozharness: 2017-02-23 04:22:42.131032Z] Running run-tests step. 20:22:42 INFO - Running pre-action listener: _resource_record_pre_action 20:22:42 INFO - Running main action method: run_tests 20:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:22:42 INFO - Python 2.7.3 20:22:42 INFO - Return code: 0 20:22:42 INFO - grabbing minidump binary from tooltool 20:22:42 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fe290>, '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 0x27feb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:22:42 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 20:22:42 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 20:22:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:22:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:22:42 INFO - Return code: 0 20:22:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:22:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:22:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:22:42 INFO - ENV: RUST_BACKTRACE is now 1 20:22:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:22:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-072 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:22:42 INFO - Using env: {'DISPLAY': ':0', 20:22:42 INFO - 'HOME': '/home/cltbld', 20:22:42 INFO - 'LANG': 'en_US.UTF-8', 20:22:42 INFO - 'LANGUAGE': 'en_US:en', 20:22:42 INFO - 'LOGNAME': 'cltbld', 20:22:42 INFO - 'MAIL': '/var/mail/cltbld', 20:22:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:22:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:42 INFO - 'MOZ_NO_REMOTE': '1', 20:22:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:22:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:42 INFO - 'NO_EM_RESTART': '1', 20:22:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:42 INFO - 'PWD': '/builds/slave/test', 20:22:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:22:42 INFO - 'RUST_BACKTRACE': '1', 20:22:42 INFO - 'SHELL': '/bin/bash', 20:22:42 INFO - 'SHLVL': '1', 20:22:42 INFO - 'TERM': 'linux', 20:22:42 INFO - 'TMOUT': '86400', 20:22:42 INFO - 'USER': 'cltbld', 20:22:42 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181', 20:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:42 INFO - '_': '/tools/buildbot/bin/python'} 20:22:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:22:42 INFO - mozversion application_buildid: 20170221181700 20:22:42 INFO - mozversion application_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:42 INFO - mozversion application_display_name: Nightly 20:22:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:22:42 INFO - mozversion application_name: Firefox 20:22:42 INFO - mozversion application_remotingname: firefox 20:22:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:22:42 INFO - mozversion application_vendor: Mozilla 20:22:42 INFO - mozversion application_version: 54.0a1 20:22:42 INFO - mozversion platform_buildid: 20170221181700 20:22:42 INFO - mozversion platform_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:22:42 INFO - mozversion platform_version: 54.0a1 20:22:42 INFO - using testdate: 1487823762 20:22:42 INFO - actual date: 1487823762 20:22:42 INFO - starting webserver on 'localhost:49627' 20:22:42 INFO - SUITE-START | Running 2 tests 20:22:42 INFO - TEST-START | dromaeo_css 20:22:42 INFO - operating with platform_type : linux_ 20:22:42 INFO - Initialising browser for dromaeo_css test... 20:22:42 INFO - TEST-INFO | started process 13852 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiFfE4j/profile http://localhost:49627/getInfo.html) 20:22:45 INFO - PROCESS | 13852 | Iterating over profile /tmp/tmpiFfE4j/profile 20:22:46 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/crashes = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/addons.json = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/bookmarkbackups = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/user.js = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:22:46 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | __metrics Screen width/height:1600/1200 20:22:47 INFO - PROCESS | 13852 | colorDepth:24 20:22:47 INFO - PROCESS | 13852 | Browser inner width/height: 1024/697 20:22:47 INFO - PROCESS | 13852 | __metrics 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/frequencyCap.json = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/permissions.sqlite = Wed Feb 22 2017 20:22:43 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/localstore.rdf = Wed Feb 22 2017 20:22:01 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/startupCache = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/minidumps = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | Stat failure 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/places.sqlite-wal = Wed Feb 22 2017 20:22:47 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/pluginreg.dat = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/webappsstore.sqlite = Wed Feb 22 2017 20:22:46 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/gmp = Wed Feb 22 2017 20:22:43 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/prefs.js = Wed Feb 22 2017 20:22:01 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/content-prefs.sqlite = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/cache2 = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/directoryLinks.json = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/.parentlock = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/cert8.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/key3.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/blocklist.xml = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/places.sqlite = Wed Feb 22 2017 20:22:47 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/safebrowsing = Wed Feb 22 2017 20:22:47 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/secmod.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | Stat failure 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/extensions.json = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/extensions = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/extensions.ini = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/mimeTypes.rdf = Wed Feb 22 2017 20:22:46 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/compatibility.ini = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/places.sqlite-shm = Wed Feb 22 2017 20:22:43 GMT-0800 (PST) 20:22:47 INFO - PROCESS | 13852 | No birth date. Using mtime. 20:22:47 INFO - PROCESS | 13852 | Using date: /tmp/tmpiFfE4j/profile/thumbnails = Wed Feb 22 2017 20:22:45 GMT-0800 (PST) 20:22:48 ERROR - PROCESS | 13852 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:22:49 INFO - TEST-INFO | 13852: exit 0 20:22:49 INFO - Browser initialized. 20:22:49 INFO - Running cycle 1/1 for dromaeo_css test... 20:22:49 INFO - TEST-INFO | started process 13982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiFfE4j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:23:01 INFO - PROCESS | 13982 | RSS: Main: 153370624 20:23:01 INFO - PROCESS | 13982 | 20:26:04 INFO - PROCESS | 13982 | Cycle 1(1): loaded http://localhost:49627/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49627/tests/dromaeo/cssquery-ext.html) 20:26:06 INFO - PROCESS | 13982 | RSS: Main: 163057664 20:26:06 INFO - PROCESS | 13982 | 20:27:49 INFO - PROCESS | 13982 | ************************* 20:27:49 INFO - PROCESS | 13982 | A coding exception was thrown and uncaught in a Task. 20:27:49 INFO - PROCESS | 13982 | 20:27:49 ERROR - PROCESS | 13982 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:27:49 INFO - PROCESS | 13982 | Full stack: 20:27:49 INFO - PROCESS | 13982 | ************************* 20:29:08 INFO - PROCESS | 13982 | Cycle 1(1): loaded http://localhost:49627/tests/dromaeo/cssquery-ext.html (next: http://localhost:49627/tests/dromaeo/cssquery-jquery.html) 20:29:09 INFO - PROCESS | 13982 | RSS: Main: 157896704 20:29:09 INFO - PROCESS | 13982 | 20:32:11 INFO - PROCESS | 13982 | Cycle 1(1): loaded http://localhost:49627/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49627/tests/dromaeo/cssquery-mootools.html) 20:32:12 INFO - PROCESS | 13982 | RSS: Main: 165720064 20:32:12 INFO - PROCESS | 13982 | 20:35:14 INFO - PROCESS | 13982 | Cycle 1(1): loaded http://localhost:49627/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49627/tests/dromaeo/cssquery-prototype.html) 20:35:14 INFO - PROCESS | 13982 | RSS: Main: 157573120 20:35:14 INFO - PROCESS | 13982 | 20:38:17 INFO - PROCESS | 13982 | Cycle 1(1): loaded http://localhost:49627/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49627/tests/dromaeo/cssquery-yui.html) 20:38:18 INFO - PROCESS | 13982 | RSS: Main: 165974016 20:38:18 INFO - PROCESS | 13982 | 20:41:21 INFO - PROCESS | 13982 | Cycle 1(1): loaded http://localhost:49627/tests/dromaeo/cssquery-yui.html (next: http://localhost:49627/tests/dromaeo/cssquery-dojo.html) 20:41:21 INFO - PROCESS | 13982 | RSS: Main: 157995008 20:41:21 INFO - PROCESS | 13982 | 20:41:21 INFO - PROCESS | 13982 | __start_tp_report 20:41:21 INFO - PROCESS | 13982 | _x_x_mozilla_page_load 20:41:21 INFO - PROCESS | 13982 | _x_x_mozilla_page_load_details 20:41:21 INFO - PROCESS | 13982 | |i|pagename|runs| 20:41:21 INFO - PROCESS | 13982 | |0;dojo.html;2509.1970569417786;2516.5847635140203;2517.4209931715704;2546.427053912871;2549.4008907906637;4321.82712691492;4459.085887393081;4491.618212451942;4529.071540334232;4604.907901841962;4360.520342762294;4515.435570553681;4523.45718513778;4563.56646118619;4580;4162.375643653446;4173.8330466781445;4189.28782107041;4198.97900510497;4248.787560621972;4248.086661367805;4273.166732487166;4286.678499112579;4294.806733696975;4328.480582330109;4097.09863829957;4127.277726397869;4132.710710250284;4253.638440732541;4268.061026574149;4161.9375709364385;4187.560306167839;4192.056787222881;4221.936670949938;4224.302990006645;4154.605312495308;4165.250254954107;4168.416421700964;4204.453421055274;4214.157168566268;4356.172327257811;4365.607095361401;4493.707908985905;4547.47704014036;4569.451665800092;4783.330333753277;4800.135975524404;4813.181759100961;4842.12841688496;4887.682300650446;4843.757812109416;4850.781714822841;4867.780949857265;4875.390576177978;4886.951130488721;4358.825646974149;4520.977395113069;4525.208088584484;4540.432445944257;4562.475315338712;4771.904561908812;4809.182438985381;4819.060283244804;4823.131836269506;4837.782299796518;4658.324542941255;4673.182193116259;4694.81220751173;4704.200285951397;4783.617310615143;4779.330893674888;4788.329633851264;4794.5924596408995;4796.952030479651;4818.734969576707;4717.22165842639;4738.952610473852;4774.880627984328;4776.665633405698;4787.688800227974;4539.72761634303;4615.192341340252;4621.93067103987;4622.19111655459;4663.836765713251;3998.300297447993;4033;4033.616806403387;4033.9798301008896;4063.146739184745;3563.768355056913;3578.6421357864006;3605.801680907523;3609.8556057757396;3651.94522082169;4202.054537728986;4203;4211.557786432441;4211.873643790691;4243.724157929786;4736.3369128322065;4768.950830817284;4834.854954351375;4843.588294994895;4850.466448690641;4174.519930208065;4200.3699445082875;4213.936790948138;4216.746995180299;4263.4031235627035;4377.671674624416;4391.253486907233;4394.318880573516;4431.911361772811;4436.290193569013;4375.584319489643;4545.272756358967;4547.522510136454;4552.430946131733;4595.241785105554;4702.200625893538;4721.386219791405;4734.6685731999805;4739.881502962454;4750.9287360690305;4457.086297309109;4480.731156130642;4482.081173359388;4487.41663583732;4489.573490518395;4717.2216584263215;4719.5752382285755;4727.456342520517;4729.5034021427255;4762.618990480684;4767.380240568704;4767.713937163781;4775.355327030807;4787.042591481787;4820.084184005028;4602.654800890014;4604.677672562889;4685.1332503486965;4698.248280275139;4720;4721.055788842314;4721.929171062506;4725.621950244041;4739.336492890929;4810.39870016248;4601.332806742937;4634.490206077261;4655.998960223568;4661.230896902109;4663.556962088596;4604.424446944132;4645.001324715203;4668.369770080995;4728.456227533878;4749.050189961952;4601.0797840432715;4601.539846015372;4610.193216187221;4647.581717645428;4662.0442809224705 20:41:21 INFO - PROCESS | 13982 | |1;ext.html;535.4886083789982;538.1121150102332;543.0903237077894;555.5333519843331;559.2143039030161;37325;39820.80089599548;40009.1998160037;40152.99999999993;40168;40171.19657606846;40505.392419113734;40511.79744101276;40525.55532779717;40600.39099413511;7653.081630204383;7807.8438431231625;7818.100918394401;7832.099308579501;9279.118483744034;9211.539423028855;9503.667371641994;9525.904740952607;9526.380785248972;9545.713628591118;9581.329306948517;9602.855957160647;9609.183219426357;9620.422774633544;9620.56707448163;9495.43027418357;9520.90479095207;9569.186619137346;9577.425354478684;9596.712098637052;9421.764455888657;9473.573689184002;9486.146246837749;9492.762680932963;9498.24550880244;32899.6710032899;33066.6693333066;33149.50275745842;33170;33295.16762080943;3215.6302025266928;3216.404965081444;3260.4131256373844;3263.9184020399443;3362.7646064775477;25404.110856119947;25492.8725356372;25989.610155847487;26140.8692956534;26818.195454136407;3436.1924947637285;3482.094655389617;3491.1097670093964;3537.2218112015316;3577.695895848856;236056.81971590203;236159.99999999828;236198;236202;236419;40381.394279085835;41466.378004329956;45259.853226418905;45284.77357613257;45568;49819;50548;50919.490805091475;54754.17868731972;55178.44821551733;12447.688807779878;12533.749325013448;12610.306433146081;12621.74756504883;12641.747165056648;9356.532173391302;9439.197668198143;9568.904310956941;9573.473458959825;9620.364538028552;15687.372505099896;15855.682886342209;15911.613258011392;15955.60110997211;15974.36102555908;6323.177986861679;6422.967885160544;6449.548531602836;6543.476521878239;6679.165104361955;737.660676089005;741.8367959049001;745.9589722565311;754.4228665071225;758.211460081509;26425.339366515607;27386.72613273842;27575;27873.442531149263;28023;902.3953950852945;912.8402529557301;917.4907926100944;918.9540522973958;1049.2707568240153;13797.310134493437;14526.24001439723;14530.782038269434;14798.63003424923;14869.553913382615;315018;317847;318259;318477;318495;50948.235776463385;51385.48614513882;51456;51477.48522514802;51481.22778158256;1136.6476392318243;1157.670064031747;1169.3334799164566;1173.9119566032582;1178.5698220149584;25007.624885626366;26189;26715.775691033065;27261.591076133478;27315;41597;41987.37018944657;41988;42161.156776864904;42355.576444235165;256287;257958;258463;259215;259874;21485.892570537046;22199.889000554893;23822.761772382055;24835.379115522257;25492.490150197264;36567.63432365642;40680.3897941537;41054;41212.1757564853;41546.79226603969;31864.769466137146;31882.202944925633;31910.840445798553;32056.198595035312;32978.835105825274;29902.551461728526;31504.684953151092;32378.676213237566;33009;35416.64583354133;32366.514502282014;32587.837060814545;32649.34701306008;32704.50943235901;33160;25846;28016.719832801413;29956.400871982874;32806.671933281315;32986;22920.770792291863;23045.308640740805;23344.53310933738;23409.41476463102;23565.175218867258 20:41:21 INFO - PROCESS | 13982 | |2;jquery.html;387.44982125381955;391.89418856908617;403.84048300921137;406.53654833489827;421.84180932150446;12510.81233781494;16153.515394538183;16754.916225418885;18207.180676869557;18210.72683909739;15985.680286394294;17176.656466870656;17219.311227550883;17423.390181343657;17809;5186.559142472895;5199.922001169985;5308.389535203462;5390.245365648812;5419.945800541984;5233.685978841282;5333.813316533922;5334.786608535674;5339.305890234265;5345.4387289334645;5149.974250128744;5154;5263.26314315996;5290.074237008508;5294.126469132589;5189.195674670432;5240.318758561381;5244.921326180111;5251.474852514756;5255.789768409259;4536.274196128604;4644.164050470914;4663.88340291492;4694.718316900963;4715;21994;22032.559348812934;22137.557248855093;22206.444838878997;22298;2184.923527676524;2192.243675931801;2243.6298010828195;2245.9663105053273;2337.9649305260436;13326.600201993955;13673;13855.376508057061;14239.928800355932;14507.92746036263;1653.8511533962;1700.5408539694338;1720.4322573550699;1740.530056884634;1747.9650406991789;65547.3445265546;73246.63376683083;73606.26393736048;73907;76002.6199868997;9792.853107203398;9866.654667086617;10024.79950400988;10158;10236.48817559134;12092.818607720892;13395.665108372368;13462.663433414244;13487.662808429868;13562.796558051637;6114.327423983358;6804.421624161902;7157.4274058075225;7169.5698258104685;7207.351338379571;6465.3857883501;6535.705893234815;6547.5416720830035;6785.084013658093;6795.864082718318;6689.933100668931;6695.598264104072;6757.695903684346;6876.106106206161;6974.965125174342;1822.6354729054242;1894.3464504746116;1895.2134864031548;1897.6394485047797;1933.5262860599244;758.4311766175368;769.1385648074192;780.9726659566888;807.4993504027423;814.8125931035895;17283.73033574215;17862.642747145244;17909.19408626615;18540.629187416176;18648.160832762558;787.4999375396665;790.1426951757293;800.0599295827379;803.3653413803147;809.821709412803;9863.358881672668;10562.841557376645;10565.577376904992;10573.471326433648;10598.258121931545;442155;455290;477232;480842;482760;74887.62556187184;75083.24916750872;75952.62023689847;75991.62004189944;76997.6150119257;910.4673765847053;949.5584553182803;979.6767066868059;981.6515137126217;986.2504496582505;16200.756988644944;16450.83549164493;16719.582010449834;17299.567510811827;17513.912430438275;39098.2180356397;40141.79929100336;40253.79873100616;40673.36030770839;41402.37896431496;53029.204561930834;53204;53536.46463535315;54061;54970.72514637561;7191.568505889454;7315.484624754391;7476.514026588255;7709.691612335444;7803.141654417896;11926.821097683714;12171.878281217429;12334.383280836102;12536.435860386488;12657;9736.123748862636;10457.68626941193;10745.086667633026;10871.891281087404;11353.205275630626;10688.625898093529;10717.196210284415;10821.134309255398;11110.388928609003;11171.944140279571;6017.007193813147;6689.76585819494;9700.85448718284;10596.728392592939;10886;7175;7530.661120249492;7547.622618869144;7656.464047516621;7871.094824095302;8315.667373305;9654.662086826927;9864.802703945737;10102.848457273294;11250.099992000783 20:41:21 INFO - PROCESS | 13982 | |3;mootools.html;1345.4954392102961;1359.6872719274565;1360.5850215684213;1362.1418506341004;1367.5487089260546;6826.214985276694;6979.127609048869;7118.822029449267;7310.744123955663;7336;7355.080614923135;7371.37343325818;7416.925830741691;7427.071616047994;7480.13978392485;2034.6337659221335;2051.9589608207825;2064.566441047382;2073.6889466579983;2074.802893725101;2069.5550456651818;2105.3894370632497;2110.2930518276394;2121.1939463004037;2124.3839286606944;1909.6849019911697;1998.1008546154217;2012.6578481658153;2018.1422895269525;2019.3235266185845;1978.179055691886;1993.242567824232;1993.5414854583382;1998.4404366777326;1998.4504261328107;1697.7538256952796;1749.448923589078;1949.5573275775967;1960.9705854412196;2010.9698354524694;7142.321479459443;7218.169910460259;7225.710971561131;7225.927740722578;7229.855402891965;4266.0827921996915;4319.97840010798;4350.477942646871;4399.208142534342;4413.249747542894;2680.3701130234576;2750.573661082515;2815.3806162644187;2840.815347002438;2849.800513964023;1075.537518866887;1082.5507414423005;1110.900018998286;1113.0761467981638;1145.7937571237173;9582.90417095827;9585.568649410794;9601.759956001086;9628.614855405775;9661.661841835503;3646.726495512847;3834.6740527055513;3850.172212974224;3885.514310711161;3920.666743326849;7499.925000750031;7500.249975002456;7501.699932002768;7510.812229694301;7515.13575938774;4149.190907772955;4239.491261048694;4254.851080212177;4263.317869140985;4285.0358669299785;3778.6599206071037;3896.5518965318784;3958.782266975344;3969.1069509360163;3969.5236571611013;3136.639286482038;3138.3723255348564;3190.3459790743304;3210.4221240176994;3230.9030729078167;962.3600305796567;965.5751469353462;973.3233607579157;981.4160574458186;984.5471083301761;770.5492287012048;780.8750599903988;791.8495485857669;802.3982013489882;805.3396215103676;4709.270063140219;4772.164871147535;4811.9518804811505;4902.142125128088;5014.548690617789;522.8692826793301;524.3471877512521;526.971016594091;529.2088327949663;530.5304805247307;3018.517037274026;3047.5067217063925;3130.373925214966;3135.263213144909;3163.936721265608;8140.674373025131;8289.95855020721;8367.121452247426;8459.70391036323;8462.111478294813;8337.95831020841;8480.10958849312;8708.521031343336;8713.564321783886;8735.432196907308;6234.2830574483605;6591;6600.339966003361;6604.570702904296;6637.966810165918;4719.551642593948;4776.5701086902345;4881.658283920092;4892.755362231803;4999.600031997359;7581.241875812375;7667.578283194255;7747.031621047369;7824.647890844812;7828.765137045898;7820.491668041559;7983.5209887406945;8011.879821802561;8023.318017968305;8053.718119865776;6567.868642627216;6704.262531121671;6772.322767723188;6845.110135682329;6967.338102880219;7056.188538317954;7074.752383666547;7094.11323584552;7096.929030709834;7116.430685545248;6321.431071203614;6333.778317758856;6352.714127864165;6387.265464471645;6544.476441884733;6138.631682099089;6213.782517611862;6227.346128656426;6256.468200203034;6451.741930322734;5580;5642.971785141048;5653.293338332708;5657.547396208376;5662.065759149694;4323.416338794223;4441.7779111044965;4510.819567217274;4591.747453890068;4610.953890461053;4349.086691794759;4374.518802931739;4468.79890404926;4508.301213311942;4603.93094103582 20:41:21 INFO - PROCESS | 13982 | |4;prototype.html;360.3351815899664;366.06287902968387;371.1074864949795;373.67303609341815;381.16334645453225;4944.5055494450535;4998.775055122519;5043.243513472981;5070.492950704932;5081.441041485434;4936.827211047614;4963.230699241614;4986.501349865011;4987.725675087878;5005.824796132136;3774.829802761139;3905.7427404129494;3933.724639275252;3943.5464921533962;3946.151577410856;3916.314644937139;3932.58707835676;3933.940990885139;3943.5464921533962;3952.4664170336923;3903.180332130242;3922.941155882664;3923.8038098095126;3934.822932968009;3936.6653834423964;3699.1319383711325;3894.80525973703;3924.8037598120122;3932.705047121449;3948.4669569608022;3415.52182694423;3416.829158542063;3417.7607567470286;3418.077119177833;3431.365197438482;4862.197737373329;4899.216125419951;4928.162212423897;4939.728314942677;4950.079453122675;4450.130945003107;4453.710508816916;4460.643148548141;4481.244034918534;4483.6861419700635;4943.912339285352;5256.816011439581;5257.211418287249;5288.576913846883;5302.787888484456;3750.268697603968;3751.568569614502;3755.6056614055406;3770.1894092769912;3779.622037796226;5322.308099947021;5495.175723641406;5512.33851937766;5535.612507124503;5563.221149039097;3809.866654667129;3826.579076301605;3831.1188426662025;3837.4627552142724;3842.135519508144;3880.9818894174873;3897.8635747748694;3904.5314562252142;3905.3165696003084;3932.410138479194;4213.157368526307;4435.423394958635;4447.444069491314;4447.844325448594;4481.843135490308;5281.049411106038;5282.445343238982;5290.31225940633;5310.309659744285;5310.973445132827;1276.489404238312;1292.0955331267962;1299.9545015924398;1301.355828864718;1306.3598836570202;821.1337039423465;860.8794768732382;876.3033388456192;889.8309321228945;893.2630579771715;4438.644908407319;4483.843065492692;4490.348899409567;4492.865214043584;4506.977465112654;5272.2355258487305;5301.712937978478;5319.9202011969855;5356.839294821161;5386.353637563439;4268.295731204378;4574.817007319736;4578.496365399803;4584.5186255442695;4593.425821772278;2804.233333000197;2812.690604033555;2826.745592896649;2830.462212179679;2833.943321133566;4863.927041094386;4875.731834749123;4895.412550493964;4919.77860996256;5006.6245031622775;5127.384713834289;5382.596305277119;5420.539254163361;5455.509004189563;5468.644538105011;4911;4922.655414121049;4937.456879743226;4947.476786838666;4949.901001980013;5238.611767881438;5272.99813035522;5284.286621306074;5288.461538461483;5310.601704872072;4932.605391568738;5174;5176.896462070662;5191.7168336123605;5209.322788037683;4917.967226882395;4953.925691114564;5080.513045910334;5084.9745751271;5113.463086376026;3816.904577385588;3858.575556688706;3862.2275544891695;3862.5558060823364;3871.4386413969255;5417.29355253094;5419.34967803866;5440.945590544044;5442.8639284016635;5452.91820622683;4941.382327209099;5246.632735708464;5258.6844789311235;5270.947290527046;5329.413764485981;5244.89510209786;5274.314339135888;5285.57289531832;5300.600501583754;5313.920291195713;5199.2980947573105;5229.50319719626;5237.607179461479;5241.50205730455;5241.7379131042535;5048.469910659328;5247.527722504993;5256.553192978639;5279;5282.336064139784;5156;5161.845144645667;5171.3535808024;5182.740862956913;5193.948060519347 20:41:21 INFO - PROCESS | 13982 | |5;yui.html;575.4734418007521;577.783331250781;686.1628812848327;713.0765658472279;735.554989231515;11502.712432189199;12128.454219560119;12298.815517767242;12463.18989265697;12531.060170487204;11585.42072896356;12023.75952480952;12520.749585008294;12676.429560669769;12710.68223294418;802.3701394405393;820.8973878265217;823.8022874510104;823.8599438095508;825.0470706334194;795.3120550723611;810.8378324335127;820.2084987986592;820.9917900821005;822.01358369956;833.8707500337457;834.2325060943946;834.4993004197463;835.7409203147014;838.9664413423455;835.0605568735173;836.8744688296774;838.0488145954325;838.7986883148031;839.7648658375604;610.4353473037422;613.9263288405375;615.3016326469444;619.201230412767;619.2785405003211;12221.877781222163;12528.874711252953;12863.163894346837;12925.741485170243;12971.286579238138;12493.375331233492;12678;12734.924824056307;12809.295488748116;12957.805632915513;10676;10747.78504429907;10822.080123189538;10824.567017319298;10974.506147223314;3118.5945827042565;3134.8432578371217;3239.1092449576317;3303.752218583592;3314.850831712579;60333.39666603366;62052.37947620511;62287.688561557356;62851.685741571455;63193.68403157955;9249.398789078688;9287.442753434816;9446.85829712555;9514.143727064597;9704.660336888175;18539.44381668552;18660.53348666267;18788.154533045774;18790.718139227924;18866.71699924502;6189.597676151036;6204.286507051746;6256;6276.748930042839;6384.138141350951;3424.8630054797527;3445.672661097192;3468.427709427916;3491.1151120398645;3504.737144714106;2897.0005348154855;2902.4340253650334;2942.543905694578;2945.116465060516;2949.7197766212175;486.15210608037694;492.4140273075029;495.95784358329223;506.5770081981513;510.652756125838;499.66023104289434;517.8705323669083;521.4681025354193;527.2065541360305;531.1448567805829;7728.806779830549;8012.959935200287;8107.4324797263625;8321.708740194184;8609.139086091342;572.5333852909948;611.5871786544064;612.1552257884091;615.9630422174683;617.9196704428396;3812.256609961031;3907.238088572701;3970.305196590585;3993.5806740292433;4038.838446462167;57438.71280643654;63656;63979.680101599195;65588.67205664037;67329.66335168293;55927.44072559222;56130.79290459455;56143.66635169652;57005;57136;49376.01247975092;50020.49938501923;50745;51486.48513514817;52059.219111713355;7369.77890663281;7587.962060189664;7670.27132422419;7732.420068494773;7854.72508462224;34902.476462853585;35043.29913401667;35690.821545892104;36385.27229455343;36490.27019459649;39678.60321396864;39795.60204398035;41372;41951.7902410486;42298.7885060585;9514.191293739832;9629.037096290314;9731.172850307803;10218.7092471106;11196.94401527987;10395.896041039492;10535.78928421412;12434;12920.22478651284;13451.125676830974;11000.449977501254;11596.884031159918;11977.461014254553;12042.21725587834;12098.697532561757;11229.55081796719;11466.312021278416;12012.159148859497;12108.939455302667;12435.875641243472;11531.596394126165;11570.421478925851;11775.646730598095;11844.644660660195;12001.339926303788;8222.794430139294;8709.346798989975;8726.214640682369;8730.738077857675;8884.511351875708;7987.361011119213;8526.275266602408;8905.154010369004;9329.953350233205;9593.664221752206 20:41:21 INFO - PROCESS | 13982 | __end_tp_report 20:41:21 INFO - PROCESS | 13982 | __start_cc_report 20:41:21 INFO - PROCESS | 13982 | _x_x_mozilla_cycle_collect,387 20:41:21 INFO - PROCESS | 13982 | __end_cc_report 20:41:21 INFO - PROCESS | 13982 | __startTimestamp1487824881673__endTimestamp 20:41:21 INFO - PROCESS | 13982 | 20:41:21 INFO - PROCESS | 13982 | ------- Summary: start ------- 20:41:21 INFO - PROCESS | 13982 | Number of tests: 6 20:41:21 INFO - PROCESS | 13982 | 20:41:21 INFO - PROCESS | 13982 | [#0] dojo.html Cycles:165 Average:4447.56 Median:4569.45 stddev:444.38 (9.7%) stddev-sans-first:418.92 20:41:21 INFO - PROCESS | 13982 | Values: 2509.2 2516.6 2517.4 2546.4 2549.4 4321.8 4459.1 4491.6 4529.1 4604.9 4360.5 4515.4 4523.5 4563.6 4580.0 4162.4 4173.8 4189.3 4199.0 4248.8 4248.1 4273.2 4286.7 4294.8 4328.5 4097.1 4127.3 4132.7 4253.6 4268.1 4161.9 4187.6 4192.1 4221.9 4224.3 4154.6 4165.3 4168.4 4204.5 4214.2 4356.2 4365.6 4493.7 4547.5 4569.5 4783.3 4800.1 4813.2 4842.1 4887.7 4843.8 4850.8 4867.8 4875.4 4887.0 4358.8 4521.0 4525.2 4540.4 4562.5 4771.9 4809.2 4819.1 4823.1 4837.8 4658.3 4673.2 4694.8 4704.2 4783.6 4779.3 4788.3 4794.6 4797.0 4818.7 4717.2 4739.0 4774.9 4776.7 4787.7 4539.7 4615.2 4621.9 4622.2 4663.8 3998.3 4033.0 4033.6 4034.0 4063.1 3563.8 3578.6 3605.8 3609.9 3651.9 4202.1 4203.0 4211.6 4211.9 4243.7 4736.3 4769.0 4834.9 4843.6 4850.5 4174.5 4200.4 4213.9 4216.7 4263.4 4377.7 4391.3 4394.3 4431.9 4436.3 4375.6 4545.3 4547.5 4552.4 4595.2 4702.2 4721.4 4734.7 4739.9 4750.9 4457.1 4480.7 4482.1 4487.4 4489.6 4717.2 4719.6 4727.5 4729.5 4762.6 4767.4 4767.7 4775.4 4787.0 4820.1 4602.7 4604.7 4685.1 4698.2 4720.0 4721.1 4721.9 4725.6 4739.3 4810.4 4601.3 4634.5 4656.0 4661.2 4663.6 4604.4 4645.0 4668.4 4728.5 4749.1 4601.1 4601.5 4610.2 4647.6 4662.0 20:41:21 INFO - PROCESS | 13982 | 20:41:21 INFO - PROCESS | 13982 | [#1] ext.html Cycles:180 Average:42330.79 Median:3459.14 stddev:71374.51 (2063.4%) stddev-sans-first:71505.75 20:41:21 INFO - PROCESS | 13982 | Values: 535.5 538.1 543.1 555.5 559.2 37325.0 39820.8 40009.2 40153.0 40168.0 40171.2 40505.4 40511.8 40525.6 40600.4 7653.1 7807.8 7818.1 7832.1 9279.1 9211.5 9503.7 9525.9 9526.4 9545.7 9581.3 9602.9 9609.2 9620.4 9620.6 9495.4 9520.9 9569.2 9577.4 9596.7 9421.8 9473.6 9486.1 9492.8 9498.2 32899.7 33066.7 33149.5 33170.0 33295.2 3215.6 3216.4 3260.4 3263.9 3362.8 25404.1 25492.9 25989.6 26140.9 26818.2 3436.2 3482.1 3491.1 3537.2 3577.7 236056.8 236160.0 236198.0 236202.0 236419.0 40381.4 41466.4 45259.9 45284.8 45568.0 49819.0 50548.0 50919.5 54754.2 55178.4 12447.7 12533.7 12610.3 12621.7 12641.7 9356.5 9439.2 9568.9 9573.5 9620.4 15687.4 15855.7 15911.6 15955.6 15974.4 6323.2 6423.0 6449.5 6543.5 6679.2 737.7 741.8 746.0 754.4 758.2 26425.3 27386.7 27575.0 27873.4 28023.0 902.4 912.8 917.5 919.0 1049.3 13797.3 14526.2 14530.8 14798.6 14869.6 315018.0 317847.0 318259.0 318477.0 318495.0 50948.2 51385.5 51456.0 51477.5 51481.2 1136.6 1157.7 1169.3 1173.9 1178.6 25007.6 26189.0 26715.8 27261.6 27315.0 41597.0 41987.4 41988.0 42161.2 42355.6 256287.0 257958.0 258463.0 259215.0 259874.0 21485.9 22199.9 23822.8 24835.4 25492.5 36567.6 40680.4 41054.0 41212.2 41546.8 31864.8 31882.2 31910.8 32056.2 32978.8 29902.6 31504.7 32378.7 33009.0 35416.6 32366.5 32587.8 32649.3 32704.5 33160.0 25846.0 28016.7 29956.4 32806.7 32986.0 22920.8 23045.3 23344.5 23409.4 23565.2 20:41:21 INFO - PROCESS | 13982 | 20:41:21 INFO - PROCESS | 13982 | [#2] jquery.html Cycles:180 Average:26956.29 Median:4704.86 stddev:76867.82 (1633.8%) stddev-sans-first:77057.56 20:41:21 INFO - PROCESS | 13982 | Values: 387.4 391.9 403.8 406.5 421.8 12510.8 16153.5 16754.9 18207.2 18210.7 15985.7 17176.7 17219.3 17423.4 17809.0 5186.6 5199.9 5308.4 5390.2 5419.9 5233.7 5333.8 5334.8 5339.3 5345.4 5150.0 5154.0 5263.3 5290.1 5294.1 5189.2 5240.3 5244.9 5251.5 5255.8 4536.3 4644.2 4663.9 4694.7 4715.0 21994.0 22032.6 22137.6 22206.4 22298.0 2184.9 2192.2 2243.6 2246.0 2338.0 13326.6 13673.0 13855.4 14239.9 14507.9 1653.9 1700.5 1720.4 1740.5 1748.0 65547.3 73246.6 73606.3 73907.0 76002.6 9792.9 9866.7 10024.8 10158.0 10236.5 12092.8 13395.7 13462.7 13487.7 13562.8 6114.3 6804.4 7157.4 7169.6 7207.4 6465.4 6535.7 6547.5 6785.1 6795.9 6689.9 6695.6 6757.7 6876.1 6975.0 1822.6 1894.3 1895.2 1897.6 1933.5 758.4 769.1 781.0 807.5 814.8 17283.7 17862.6 17909.2 18540.6 18648.2 787.5 790.1 800.1 803.4 809.8 9863.4 10562.8 10565.6 10573.5 10598.3 442155.0 455290.0 477232.0 480842.0 482760.0 74887.6 75083.2 75952.6 75991.6 76997.6 910.5 949.6 979.7 981.7 986.3 16200.8 16450.8 16719.6 17299.6 17513.9 39098.2 40141.8 40253.8 40673.4 41402.4 53029.2 53204.0 53536.5 54061.0 54970.7 7191.6 7315.5 7476.5 7709.7 7803.1 11926.8 12171.9 12334.4 12536.4 12657.0 9736.1 10457.7 10745.1 10871.9 11353.2 10688.6 10717.2 10821.1 11110.4 11171.9 6017.0 6689.8 9700.9 10596.7 10886.0 7175.0 7530.7 7547.6 7656.5 7871.1 8315.7 9654.7 9864.8 10102.8 11250.1 20:41:21 INFO - PROCESS | 13982 | 20:41:21 INFO - PROCESS | 13982 | [#3] mootools.html Cycles:180 Average:4694.58 Median:523.61 stddev:2572.99 (491.4%) stddev-sans-first:2567.90 20:41:21 INFO - PROCESS | 13982 | Values: 1345.5 1359.7 1360.6 1362.1 1367.5 6826.2 6979.1 7118.8 7310.7 7336.0 7355.1 7371.4 7416.9 7427.1 7480.1 2034.6 2052.0 2064.6 2073.7 2074.8 2069.6 2105.4 2110.3 2121.2 2124.4 1909.7 1998.1 2012.7 2018.1 2019.3 1978.2 1993.2 1993.5 1998.4 1998.5 1697.8 1749.4 1949.6 1961.0 2011.0 7142.3 7218.2 7225.7 7225.9 7229.9 4266.1 4320.0 4350.5 4399.2 4413.2 2680.4 2750.6 2815.4 2840.8 2849.8 1075.5 1082.6 1110.9 1113.1 1145.8 9582.9 9585.6 9601.8 9628.6 9661.7 3646.7 3834.7 3850.2 3885.5 3920.7 7499.9 7500.2 7501.7 7510.8 7515.1 4149.2 4239.5 4254.9 4263.3 4285.0 3778.7 3896.6 3958.8 3969.1 3969.5 3136.6 3138.4 3190.3 3210.4 3230.9 962.4 965.6 973.3 981.4 984.5 770.5 780.9 791.8 802.4 805.3 4709.3 4772.2 4812.0 4902.1 5014.5 522.9 524.3 527.0 529.2 530.5 3018.5 3047.5 3130.4 3135.3 3163.9 8140.7 8290.0 8367.1 8459.7 8462.1 8338.0 8480.1 8708.5 8713.6 8735.4 6234.3 6591.0 6600.3 6604.6 6638.0 4719.6 4776.6 4881.7 4892.8 4999.6 7581.2 7667.6 7747.0 7824.6 7828.8 7820.5 7983.5 8011.9 8023.3 8053.7 6567.9 6704.3 6772.3 6845.1 6967.3 7056.2 7074.8 7094.1 7096.9 7116.4 6321.4 6333.8 6352.7 6387.3 6544.5 6138.6 6213.8 6227.3 6256.5 6451.7 5580.0 5643.0 5653.3 5657.5 5662.1 4323.4 4441.8 4510.8 4591.7 4611.0 4349.1 4374.5 4468.8 4508.3 4603.9 20:41:21 INFO - PROCESS | 13982 | 20:41:21 INFO - PROCESS | 13982 | [#4] prototype.html Cycles:180 Average:4335.94 Median:4934.72 stddev:1253.09 (25.4%) stddev-sans-first:1220.56 20:41:21 INFO - PROCESS | 13982 | Values: 360.3 366.1 371.1 373.7 381.2 4944.5 4998.8 5043.2 5070.5 5081.4 4936.8 4963.2 4986.5 4987.7 5005.8 3774.8 3905.7 3933.7 3943.5 3946.2 3916.3 3932.6 3933.9 3943.5 3952.5 3903.2 3922.9 3923.8 3934.8 3936.7 3699.1 3894.8 3924.8 3932.7 3948.5 3415.5 3416.8 3417.8 3418.1 3431.4 4862.2 4899.2 4928.2 4939.7 4950.1 4450.1 4453.7 4460.6 4481.2 4483.7 4943.9 5256.8 5257.2 5288.6 5302.8 3750.3 3751.6 3755.6 3770.2 3779.6 5322.3 5495.2 5512.3 5535.6 5563.2 3809.9 3826.6 3831.1 3837.5 3842.1 3881.0 3897.9 3904.5 3905.3 3932.4 4213.2 4435.4 4447.4 4447.8 4481.8 5281.0 5282.4 5290.3 5310.3 5311.0 1276.5 1292.1 1300.0 1301.4 1306.4 821.1 860.9 876.3 889.8 893.3 4438.6 4483.8 4490.3 4492.9 4507.0 5272.2 5301.7 5319.9 5356.8 5386.4 4268.3 4574.8 4578.5 4584.5 4593.4 2804.2 2812.7 2826.7 2830.5 2833.9 4863.9 4875.7 4895.4 4919.8 5006.6 5127.4 5382.6 5420.5 5455.5 5468.6 4911.0 4922.7 4937.5 4947.5 4949.9 5238.6 5273.0 5284.3 5288.5 5310.6 4932.6 5174.0 5176.9 5191.7 5209.3 4918.0 4953.9 5080.5 5085.0 5113.5 3816.9 3858.6 3862.2 3862.6 3871.4 5417.3 5419.3 5440.9 5442.9 5452.9 4941.4 5246.6 5258.7 5270.9 5329.4 5244.9 5274.3 5285.6 5300.6 5313.9 5199.3 5229.5 5237.6 5241.5 5241.7 5048.5 5247.5 5256.6 5279.0 5282.3 5156.0 5161.8 5171.4 5182.7 5193.9 20:41:21 INFO - PROCESS | 13982 | 20:41:21 INFO - PROCESS | 13982 | [#5] yui.html Cycles:180 Average:14312.96 Median:25998.57 stddev:17906.60 (68.9%) stddev-sans-first:17927.12 20:41:21 INFO - PROCESS | 13982 | Values: 575.5 577.8 686.2 713.1 735.6 11502.7 12128.5 12298.8 12463.2 12531.1 11585.4 12023.8 12520.7 12676.4 12710.7 802.4 820.9 823.8 823.9 825.0 795.3 810.8 820.2 821.0 822.0 833.9 834.2 834.5 835.7 839.0 835.1 836.9 838.0 838.8 839.8 610.4 613.9 615.3 619.2 619.3 12221.9 12528.9 12863.2 12925.7 12971.3 12493.4 12678.0 12734.9 12809.3 12957.8 10676.0 10747.8 10822.1 10824.6 10974.5 3118.6 3134.8 3239.1 3303.8 3314.9 60333.4 62052.4 62287.7 62851.7 63193.7 9249.4 9287.4 9446.9 9514.1 9704.7 18539.4 18660.5 18788.2 18790.7 18866.7 6189.6 6204.3 6256.0 6276.7 6384.1 3424.9 3445.7 3468.4 3491.1 3504.7 2897.0 2902.4 2942.5 2945.1 2949.7 486.2 492.4 496.0 506.6 510.7 499.7 517.9 521.5 527.2 531.1 7728.8 8013.0 8107.4 8321.7 8609.1 572.5 611.6 612.2 616.0 617.9 3812.3 3907.2 3970.3 3993.6 4038.8 57438.7 63656.0 63979.7 65588.7 67329.7 55927.4 56130.8 56143.7 57005.0 57136.0 49376.0 50020.5 50745.0 51486.5 52059.2 7369.8 7588.0 7670.3 7732.4 7854.7 34902.5 35043.3 35690.8 36385.3 36490.3 39678.6 39795.6 41372.0 41951.8 42298.8 9514.2 9629.0 9731.2 10218.7 11196.9 10395.9 10535.8 12434.0 12920.2 13451.1 11000.4 11596.9 11977.5 12042.2 12098.7 11229.6 11466.3 12012.2 12108.9 12435.9 11531.6 11570.4 11775.6 11844.6 12001.3 8222.8 8709.3 8726.2 8730.7 8884.5 7987.4 8526.3 8905.2 9330.0 9593.7 20:41:21 INFO - PROCESS | 13982 | -------- Summary: end -------- 20:41:21 INFO - PROCESS | 13982 | 20:41:22 INFO - TEST-INFO | 13982: exit 0 20:41:22 INFO - TEST-OK | dromaeo_css | took 1120149ms 20:41:22 INFO - TEST-START | kraken 20:41:22 INFO - operating with platform_type : linux_ 20:41:22 INFO - Initialising browser for kraken test... 20:41:22 INFO - TEST-INFO | started process 14355 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwnReap/profile http://localhost:49627/getInfo.html) 20:41:25 INFO - PROCESS | 14355 | Iterating over profile /tmp/tmpwnReap/profile 20:41:26 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/crashes = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/addons.json = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:26 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:26 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/bookmarkbackups = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/user.js = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:41:26 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/frequencyCap.json = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/permissions.sqlite = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/localstore.rdf = Wed Feb 22 2017 20:22:01 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/startupCache = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/minidumps = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | __metrics Screen width/height:1600/1200 20:41:27 INFO - PROCESS | 14355 | colorDepth:24 20:41:27 INFO - PROCESS | 14355 | Browser inner width/height: 1024/697 20:41:27 INFO - PROCESS | 14355 | __metrics 20:41:27 INFO - PROCESS | 14355 | Stat failure 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/places.sqlite-wal = Wed Feb 22 2017 20:41:27 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/pluginreg.dat = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/webappsstore.sqlite = Wed Feb 22 2017 20:41:26 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/gmp = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/prefs.js = Wed Feb 22 2017 20:22:01 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/content-prefs.sqlite = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/cache2 = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/directoryLinks.json = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/.parentlock = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/cert8.db = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/key3.db = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/blocklist.xml = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/places.sqlite = Wed Feb 22 2017 20:41:27 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/safebrowsing = Wed Feb 22 2017 20:41:27 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/secmod.db = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | Stat failure 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/extensions.json = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/extensions = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/extensions.ini = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/mimeTypes.rdf = Wed Feb 22 2017 20:41:26 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/compatibility.ini = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/places.sqlite-shm = Wed Feb 22 2017 20:41:24 GMT-0800 (PST) 20:41:27 INFO - PROCESS | 14355 | No birth date. Using mtime. 20:41:27 INFO - PROCESS | 14355 | Using date: /tmp/tmpwnReap/profile/thumbnails = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:27 ERROR - PROCESS | 14355 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:41:28 INFO - TEST-INFO | 14355: exit 0 20:41:28 INFO - Browser initialized. 20:41:28 INFO - Running cycle 1/1 for kraken test... 20:41:28 INFO - TEST-INFO | started process 14483 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwnReap/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:41:41 INFO - PROCESS | 14483 | RSS: Main: 153907200 20:41:41 INFO - PROCESS | 14483 | 20:41:43 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49627/tests/kraken/driver.html?testName=audio-beat-detection) 20:41:44 INFO - PROCESS | 14483 | RSS: Main: 160886784 20:41:44 INFO - PROCESS | 14483 | 20:41:47 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49627/tests/kraken/driver.html?testName=audio-dft) 20:41:48 INFO - PROCESS | 14483 | RSS: Main: 159080448 20:41:48 INFO - PROCESS | 14483 | 20:41:51 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49627/tests/kraken/driver.html?testName=audio-fft) 20:41:51 INFO - PROCESS | 14483 | RSS: Main: 155893760 20:41:51 INFO - PROCESS | 14483 | 20:41:54 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49627/tests/kraken/driver.html?testName=audio-oscillator) 20:41:54 INFO - PROCESS | 14483 | RSS: Main: 156327936 20:41:54 INFO - PROCESS | 14483 | 20:41:56 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49627/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:41:57 INFO - PROCESS | 14483 | RSS: Main: 156491776 20:41:57 INFO - PROCESS | 14483 | 20:42:00 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49627/tests/kraken/driver.html?testName=imaging-darkroom) 20:42:01 INFO - PROCESS | 14483 | RSS: Main: 161763328 20:42:01 INFO - PROCESS | 14483 | 20:42:04 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49627/tests/kraken/driver.html?testName=imaging-desaturate) 20:42:05 INFO - PROCESS | 14483 | RSS: Main: 158310400 20:42:05 INFO - PROCESS | 14483 | 20:42:08 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49627/tests/kraken/driver.html?testName=json-parse-financial) 20:42:08 INFO - PROCESS | 14483 | RSS: Main: 159014912 20:42:08 INFO - PROCESS | 14483 | 20:42:10 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49627/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:42:11 INFO - PROCESS | 14483 | RSS: Main: 159408128 20:42:11 INFO - PROCESS | 14483 | 20:42:13 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49627/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:42:13 INFO - PROCESS | 14483 | RSS: Main: 159379456 20:42:13 INFO - PROCESS | 14483 | 20:42:16 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49627/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:42:16 INFO - PROCESS | 14483 | RSS: Main: 160202752 20:42:16 INFO - PROCESS | 14483 | 20:42:19 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49627/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:42:20 INFO - PROCESS | 14483 | RSS: Main: 160305152 20:42:20 INFO - PROCESS | 14483 | 20:42:23 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49627/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:42:24 INFO - PROCESS | 14483 | RSS: Main: 163115008 20:42:24 INFO - PROCESS | 14483 | 20:42:25 INFO - PROCESS | 14483 | Cycle 1(1): loaded http://localhost:49627/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49627/tests/kraken/driver.html?testName=ai-astar) 20:42:26 INFO - PROCESS | 14483 | RSS: Main: 164315136 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | __start_tp_report 20:42:26 INFO - PROCESS | 14483 | _x_x_mozilla_page_load 20:42:26 INFO - PROCESS | 14483 | _x_x_mozilla_page_load_details 20:42:26 INFO - PROCESS | 14483 | |i|pagename|runs| 20:42:26 INFO - PROCESS | 14483 | |0;ai-astar;108;115;116;116;115;114;115;115;115;114 20:42:26 INFO - PROCESS | 14483 | |1;audio-beat-detection;153;127;128;171;133;133;134;127;150;130 20:42:26 INFO - PROCESS | 14483 | |2;audio-dft;166;171;169;167;174;163;165;164;165;163 20:42:26 INFO - PROCESS | 14483 | |3;audio-fft;76;76;77;123;77;81;77;78;95;79 20:42:26 INFO - PROCESS | 14483 | |4;audio-oscillator;86;84;108;84;84;85;105;84;85;115 20:42:26 INFO - PROCESS | 14483 | |5;imaging-gaussian-blur;115;114;113;114;114;115;114;114;114;114 20:42:26 INFO - PROCESS | 14483 | |6;imaging-darkroom;151;151;152;151;149;150;152;152;151;155 20:42:26 INFO - PROCESS | 14483 | |7;imaging-desaturate;96;97;96;96;97;97;96;96;96;95 20:42:26 INFO - PROCESS | 14483 | |8;json-parse-financial;69;66;65;68;70;70;67;67;68;66 20:42:26 INFO - PROCESS | 14483 | |9;json-stringify-tinderbox;66;70;67;66;65;65;65;65;69;66 20:42:26 INFO - PROCESS | 14483 | |10;stanford-crypto-aes;89;89;88;89;90;91;89;89;91;88 20:42:26 INFO - PROCESS | 14483 | |11;stanford-crypto-ccm;150;140;143;138;146;141;148;137;187;139 20:42:26 INFO - PROCESS | 14483 | |12;stanford-crypto-pbkdf2;189;190;183;188;191;185;183;187;180;185 20:42:26 INFO - PROCESS | 14483 | |13;stanford-crypto-sha256-iterative;75;69;70;72;70;71;70;70;76;74 20:42:26 INFO - PROCESS | 14483 | __end_tp_report 20:42:26 INFO - PROCESS | 14483 | __start_cc_report 20:42:26 INFO - PROCESS | 14483 | _x_x_mozilla_cycle_collect,875 20:42:26 INFO - PROCESS | 14483 | __end_cc_report 20:42:26 INFO - PROCESS | 14483 | __startTimestamp1487824946154__endTimestamp 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | ------- Summary: start ------- 20:42:26 INFO - PROCESS | 14483 | Number of tests: 14 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:2.31 (2.0%) stddev-sans-first:0.71 20:42:26 INFO - PROCESS | 14483 | Values: 108.0 115.0 116.0 116.0 115.0 114.0 115.0 115.0 115.0 114.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:133.50 stddev:14.63 (11.0%) stddev-sans-first:14.56 20:42:26 INFO - PROCESS | 14483 | Values: 153.0 127.0 128.0 171.0 133.0 133.0 134.0 127.0 150.0 130.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#2] audio-dft Cycles:10 Average:166.70 Median:166.50 stddev:3.62 (2.2%) stddev-sans-first:3.83 20:42:26 INFO - PROCESS | 14483 | Values: 166.0 171.0 169.0 167.0 174.0 163.0 165.0 164.0 165.0 163.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#3] audio-fft Cycles:10 Average:83.90 Median:77.50 stddev:14.86 (19.2%) stddev-sans-first:15.48 20:42:26 INFO - PROCESS | 14483 | Values: 76.0 76.0 77.0 123.0 77.0 81.0 77.0 78.0 95.0 79.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:84.00 stddev:12.22 (14.5%) stddev-sans-first:12.77 20:42:26 INFO - PROCESS | 14483 | Values: 86.0 84.0 108.0 84.0 84.0 85.0 105.0 84.0 85.0 115.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 20:42:26 INFO - PROCESS | 14483 | Values: 115.0 114.0 113.0 114.0 114.0 115.0 114.0 114.0 114.0 114.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:151.50 stddev:1.58 (1.0%) stddev-sans-first:1.67 20:42:26 INFO - PROCESS | 14483 | Values: 151.0 151.0 152.0 151.0 149.0 150.0 152.0 152.0 151.0 155.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 20:42:26 INFO - PROCESS | 14483 | Values: 96.0 97.0 96.0 96.0 97.0 97.0 96.0 96.0 96.0 95.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#8] json-parse-financial Cycles:10 Average:67.60 Median:68.00 stddev:1.71 (2.5%) stddev-sans-first:1.74 20:42:26 INFO - PROCESS | 14483 | Values: 69.0 66.0 65.0 68.0 70.0 70.0 67.0 67.0 68.0 66.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:1.78 (2.7%) stddev-sans-first:1.88 20:42:26 INFO - PROCESS | 14483 | Values: 66.0 70.0 67.0 66.0 65.0 65.0 65.0 65.0 69.0 66.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:89.00 stddev:1.06 (1.2%) stddev-sans-first:1.12 20:42:26 INFO - PROCESS | 14483 | Values: 89.0 89.0 88.0 89.0 90.0 91.0 89.0 89.0 91.0 88.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:144.50 stddev:14.75 (10.2%) stddev-sans-first:15.60 20:42:26 INFO - PROCESS | 14483 | Values: 150.0 140.0 143.0 138.0 146.0 141.0 148.0 137.0 187.0 139.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.10 Median:187.50 stddev:3.51 (1.9%) stddev-sans-first:3.56 20:42:26 INFO - PROCESS | 14483 | Values: 189.0 190.0 183.0 188.0 191.0 185.0 183.0 187.0 180.0 185.0 20:42:26 INFO - PROCESS | 14483 | 20:42:26 INFO - PROCESS | 14483 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:71.50 stddev:2.45 (3.4%) stddev-sans-first:2.29 20:42:26 INFO - PROCESS | 14483 | Values: 75.0 69.0 70.0 72.0 70.0 71.0 70.0 70.0 76.0 74.0 20:42:26 INFO - PROCESS | 14483 | -------- Summary: end -------- 20:42:26 INFO - PROCESS | 14483 | 20:42:27 INFO - TEST-INFO | 14483: exit 0 20:42:27 INFO - TEST-OK | kraken | took 65109ms 20:42:27 INFO - SUITE-END | took 1185s 20:42:27 INFO - Completed test suite (00:19:45) 20:42:27 INFO - javascript benchmark 20:42:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6209.637075869865, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2509.1970569417786, 2516.5847635140203, 2517.4209931715704, 2546.427053912871, 2549.4008907906637, 4321.82712691492, 4459.085887393081, 4491.618212451942, 4529.071540334232, 4604.907901841962, 4360.520342762294, 4515.435570553681, 4523.45718513778, 4563.56646118619, 4580.0, 4162.375643653446, 4173.8330466781445, 4189.28782107041, 4198.97900510497, 4248.787560621972, 4248.086661367805, 4273.166732487166, 4286.678499112579, 4294.806733696975, 4328.480582330109, 4097.09863829957, 4127.277726397869, 4132.710710250284, 4253.638440732541, 4268.061026574149, 4161.9375709364385, 4187.560306167839, 4192.056787222881, 4221.936670949938, 4224.302990006645, 4154.605312495308, 4165.250254954107, 4168.416421700964, 4204.453421055274, 4214.157168566268, 4356.172327257811, 4365.607095361401, 4493.707908985905, 4547.47704014036, 4569.451665800092, 4783.330333753277, 4800.135975524404, 4813.181759100961, 4842.12841688496, 4887.682300650446, 4843.757812109416, 4850.781714822841, 4867.780949857265, 4875.390576177978, 4886.951130488721, 4358.825646974149, 4520.977395113069, 4525.208088584484, 4540.432445944257, 4562.475315338712, 4771.904561908812, 4809.182438985381, 4819.060283244804, 4823.131836269506, 4837.782299796518, 4658.324542941255, 4673.182193116259, 4694.81220751173, 4704.200285951397, 4783.617310615143, 4779.330893674888, 4788.329633851264, 4794.5924596408995, 4796.952030479651, 4818.734969576707, 4717.22165842639, 4738.952610473852, 4774.880627984328, 4776.665633405698, 4787.688800227974, 4539.72761634303, 4615.192341340252, 4621.93067103987, 4622.19111655459, 4663.836765713251, 3998.300297447993, 4033.0, 4033.616806403387, 4033.9798301008896, 4063.146739184745, 3563.768355056913, 3578.6421357864006, 3605.801680907523, 3609.8556057757396, 3651.94522082169, 4202.054537728986, 4203.0, 4211.557786432441, 4211.873643790691, 4243.724157929786, 4736.3369128322065, 4768.950830817284, 4834.854954351375, 4843.588294994895, 4850.466448690641, 4174.519930208065, 4200.3699445082875, 4213.936790948138, 4216.746995180299, 4263.4031235627035, 4377.671674624416, 4391.253486907233, 4394.318880573516, 4431.911361772811, 4436.290193569013, 4375.584319489643, 4545.272756358967, 4547.522510136454, 4552.430946131733, 4595.241785105554, 4702.200625893538, 4721.386219791405, 4734.6685731999805, 4739.881502962454, 4750.9287360690305, 4457.086297309109, 4480.731156130642, 4482.081173359388, 4487.41663583732, 4489.573490518395, 4717.2216584263215, 4719.5752382285755, 4727.456342520517, 4729.5034021427255, 4762.618990480684, 4767.380240568704, 4767.713937163781, 4775.355327030807, 4787.042591481787, 4820.084184005028, 4602.654800890014, 4604.677672562889, 4685.1332503486965, 4698.248280275139, 4720.0, 4721.055788842314, 4721.929171062506, 4725.621950244041, 4739.336492890929, 4810.39870016248, 4601.332806742937, 4634.490206077261, 4655.998960223568, 4661.230896902109, 4663.556962088596, 4604.424446944132, 4645.001324715203, 4668.369770080995, 4728.456227533878, 4749.050189961952, 4601.0797840432715, 4601.539846015372, 4610.193216187221, 4647.581717645428, 4662.0442809224705], "value": 4420.150494418598, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.4886083789982, 538.1121150102332, 543.0903237077894, 555.5333519843331, 559.2143039030161, 37325.0, 39820.80089599548, 40009.1998160037, 40152.99999999993, 40168.0, 40171.19657606846, 40505.392419113734, 40511.79744101276, 40525.55532779717, 40600.39099413511, 7653.081630204383, 7807.8438431231625, 7818.100918394401, 7832.099308579501, 9279.118483744034, 9211.539423028855, 9503.667371641994, 9525.904740952607, 9526.380785248972, 9545.713628591118, 9581.329306948517, 9602.855957160647, 9609.183219426357, 9620.422774633544, 9620.56707448163, 9495.43027418357, 9520.90479095207, 9569.186619137346, 9577.425354478684, 9596.712098637052, 9421.764455888657, 9473.573689184002, 9486.146246837749, 9492.762680932963, 9498.24550880244, 32899.6710032899, 33066.6693333066, 33149.50275745842, 33170.0, 33295.16762080943, 3215.6302025266928, 3216.404965081444, 3260.4131256373844, 3263.9184020399443, 3362.7646064775477, 25404.110856119947, 25492.8725356372, 25989.610155847487, 26140.8692956534, 26818.195454136407, 3436.1924947637285, 3482.094655389617, 3491.1097670093964, 3537.2218112015316, 3577.695895848856, 236056.81971590203, 236159.99999999828, 236198.0, 236202.0, 236419.0, 40381.394279085835, 41466.378004329956, 45259.853226418905, 45284.77357613257, 45568.0, 49819.0, 50548.0, 50919.490805091475, 54754.17868731972, 55178.44821551733, 12447.688807779878, 12533.749325013448, 12610.306433146081, 12621.74756504883, 12641.747165056648, 9356.532173391302, 9439.197668198143, 9568.904310956941, 9573.473458959825, 9620.364538028552, 15687.372505099896, 15855.682886342209, 15911.613258011392, 15955.60110997211, 15974.36102555908, 6323.177986861679, 6422.967885160544, 6449.548531602836, 6543.476521878239, 6679.165104361955, 737.660676089005, 741.8367959049001, 745.9589722565311, 754.4228665071225, 758.211460081509, 26425.339366515607, 27386.72613273842, 27575.0, 27873.442531149263, 28023.0, 902.3953950852945, 912.8402529557301, 917.4907926100944, 918.9540522973958, 1049.2707568240153, 13797.310134493437, 14526.24001439723, 14530.782038269434, 14798.63003424923, 14869.553913382615, 315018.0, 317847.0, 318259.0, 318477.0, 318495.0, 50948.235776463385, 51385.48614513882, 51456.0, 51477.48522514802, 51481.22778158256, 1136.6476392318243, 1157.670064031747, 1169.3334799164566, 1173.9119566032582, 1178.5698220149584, 25007.624885626366, 26189.0, 26715.775691033065, 27261.591076133478, 27315.0, 41597.0, 41987.37018944657, 41988.0, 42161.156776864904, 42355.576444235165, 256287.0, 257958.0, 258463.0, 259215.0, 259874.0, 21485.892570537046, 22199.889000554893, 23822.761772382055, 24835.379115522257, 25492.490150197264, 36567.63432365642, 40680.3897941537, 41054.0, 41212.1757564853, 41546.79226603969, 31864.769466137146, 31882.202944925633, 31910.840445798553, 32056.198595035312, 32978.835105825274, 29902.551461728526, 31504.684953151092, 32378.676213237566, 33009.0, 35416.64583354133, 32366.514502282014, 32587.837060814545, 32649.34701306008, 32704.50943235901, 33160.0, 25846.0, 28016.719832801413, 29956.400871982874, 32806.671933281315, 32986.0, 22920.770792291863, 23045.308640740805, 23344.53310933738, 23409.41476463102, 23565.175218867258], "value": 16804.655963009485, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.44982125381955, 391.89418856908617, 403.84048300921137, 406.53654833489827, 421.84180932150446, 12510.81233781494, 16153.515394538183, 16754.916225418885, 18207.180676869557, 18210.72683909739, 15985.680286394294, 17176.656466870656, 17219.311227550883, 17423.390181343657, 17809.0, 5186.559142472895, 5199.922001169985, 5308.389535203462, 5390.245365648812, 5419.945800541984, 5233.685978841282, 5333.813316533922, 5334.786608535674, 5339.305890234265, 5345.4387289334645, 5149.974250128744, 5154.0, 5263.26314315996, 5290.074237008508, 5294.126469132589, 5189.195674670432, 5240.318758561381, 5244.921326180111, 5251.474852514756, 5255.789768409259, 4536.274196128604, 4644.164050470914, 4663.88340291492, 4694.718316900963, 4715.0, 21994.0, 22032.559348812934, 22137.557248855093, 22206.444838878997, 22298.0, 2184.923527676524, 2192.243675931801, 2243.6298010828195, 2245.9663105053273, 2337.9649305260436, 13326.600201993955, 13673.0, 13855.376508057061, 14239.928800355932, 14507.92746036263, 1653.8511533962, 1700.5408539694338, 1720.4322573550699, 1740.530056884634, 1747.9650406991789, 65547.3445265546, 73246.63376683083, 73606.26393736048, 73907.0, 76002.6199868997, 9792.853107203398, 9866.654667086617, 10024.79950400988, 10158.0, 10236.48817559134, 12092.818607720892, 13395.665108372368, 13462.663433414244, 13487.662808429868, 13562.796558051637, 6114.327423983358, 6804.421624161902, 7157.4274058075225, 7169.5698258104685, 7207.351338379571, 6465.3857883501, 6535.705893234815, 6547.5416720830035, 6785.084013658093, 6795.864082718318, 6689.933100668931, 6695.598264104072, 6757.695903684346, 6876.106106206161, 6974.965125174342, 1822.6354729054242, 1894.3464504746116, 1895.2134864031548, 1897.6394485047797, 1933.5262860599244, 758.4311766175368, 769.1385648074192, 780.9726659566888, 807.4993504027423, 814.8125931035895, 17283.73033574215, 17862.642747145244, 17909.19408626615, 18540.629187416176, 18648.160832762558, 787.4999375396665, 790.1426951757293, 800.0599295827379, 803.3653413803147, 809.821709412803, 9863.358881672668, 10562.841557376645, 10565.577376904992, 10573.471326433648, 10598.258121931545, 442155.0, 455290.0, 477232.0, 480842.0, 482760.0, 74887.62556187184, 75083.24916750872, 75952.62023689847, 75991.62004189944, 76997.6150119257, 910.4673765847053, 949.5584553182803, 979.6767066868059, 981.6515137126217, 986.2504496582505, 16200.756988644944, 16450.83549164493, 16719.582010449834, 17299.567510811827, 17513.912430438275, 39098.2180356397, 40141.79929100336, 40253.79873100616, 40673.36030770839, 41402.37896431496, 53029.204561930834, 53204.0, 53536.46463535315, 54061.0, 54970.72514637561, 7191.568505889454, 7315.484624754391, 7476.514026588255, 7709.691612335444, 7803.141654417896, 11926.821097683714, 12171.878281217429, 12334.383280836102, 12536.435860386488, 12657.0, 9736.123748862636, 10457.68626941193, 10745.086667633026, 10871.891281087404, 11353.205275630626, 10688.625898093529, 10717.196210284415, 10821.134309255398, 11110.388928609003, 11171.944140279571, 6017.007193813147, 6689.76585819494, 9700.85448718284, 10596.728392592939, 10886.0, 7175.0, 7530.661120249492, 7547.622618869144, 7656.464047516621, 7871.094824095302, 8315.667373305, 9654.662086826927, 9864.802703945737, 10102.848457273294, 11250.099992000783], "value": 8491.753896891349, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.4954392102961, 1359.6872719274565, 1360.5850215684213, 1362.1418506341004, 1367.5487089260546, 6826.214985276694, 6979.127609048869, 7118.822029449267, 7310.744123955663, 7336.0, 7355.080614923135, 7371.37343325818, 7416.925830741691, 7427.071616047994, 7480.13978392485, 2034.6337659221335, 2051.9589608207825, 2064.566441047382, 2073.6889466579983, 2074.802893725101, 2069.5550456651818, 2105.3894370632497, 2110.2930518276394, 2121.1939463004037, 2124.3839286606944, 1909.6849019911697, 1998.1008546154217, 2012.6578481658153, 2018.1422895269525, 2019.3235266185845, 1978.179055691886, 1993.242567824232, 1993.5414854583382, 1998.4404366777326, 1998.4504261328107, 1697.7538256952796, 1749.448923589078, 1949.5573275775967, 1960.9705854412196, 2010.9698354524694, 7142.321479459443, 7218.169910460259, 7225.710971561131, 7225.927740722578, 7229.855402891965, 4266.0827921996915, 4319.97840010798, 4350.477942646871, 4399.208142534342, 4413.249747542894, 2680.3701130234576, 2750.573661082515, 2815.3806162644187, 2840.815347002438, 2849.800513964023, 1075.537518866887, 1082.5507414423005, 1110.900018998286, 1113.0761467981638, 1145.7937571237173, 9582.90417095827, 9585.568649410794, 9601.759956001086, 9628.614855405775, 9661.661841835503, 3646.726495512847, 3834.6740527055513, 3850.172212974224, 3885.514310711161, 3920.666743326849, 7499.925000750031, 7500.249975002456, 7501.699932002768, 7510.812229694301, 7515.13575938774, 4149.190907772955, 4239.491261048694, 4254.851080212177, 4263.317869140985, 4285.0358669299785, 3778.6599206071037, 3896.5518965318784, 3958.782266975344, 3969.1069509360163, 3969.5236571611013, 3136.639286482038, 3138.3723255348564, 3190.3459790743304, 3210.4221240176994, 3230.9030729078167, 962.3600305796567, 965.5751469353462, 973.3233607579157, 981.4160574458186, 984.5471083301761, 770.5492287012048, 780.8750599903988, 791.8495485857669, 802.3982013489882, 805.3396215103676, 4709.270063140219, 4772.164871147535, 4811.9518804811505, 4902.142125128088, 5014.548690617789, 522.8692826793301, 524.3471877512521, 526.971016594091, 529.2088327949663, 530.5304805247307, 3018.517037274026, 3047.5067217063925, 3130.373925214966, 3135.263213144909, 3163.936721265608, 8140.674373025131, 8289.95855020721, 8367.121452247426, 8459.70391036323, 8462.111478294813, 8337.95831020841, 8480.10958849312, 8708.521031343336, 8713.564321783886, 8735.432196907308, 6234.2830574483605, 6591.0, 6600.339966003361, 6604.570702904296, 6637.966810165918, 4719.551642593948, 4776.5701086902345, 4881.658283920092, 4892.755362231803, 4999.600031997359, 7581.241875812375, 7667.578283194255, 7747.031621047369, 7824.647890844812, 7828.765137045898, 7820.491668041559, 7983.5209887406945, 8011.879821802561, 8023.318017968305, 8053.718119865776, 6567.868642627216, 6704.262531121671, 6772.322767723188, 6845.110135682329, 6967.338102880219, 7056.188538317954, 7074.752383666547, 7094.11323584552, 7096.929030709834, 7116.430685545248, 6321.431071203614, 6333.778317758856, 6352.714127864165, 6387.265464471645, 6544.476441884733, 6138.631682099089, 6213.782517611862, 6227.346128656426, 6256.468200203034, 6451.741930322734, 5580.0, 5642.971785141048, 5653.293338332708, 5657.547396208376, 5662.065759149694, 4323.416338794223, 4441.7779111044965, 4510.819567217274, 4591.747453890068, 4610.953890461053, 4349.086691794759, 4374.518802931739, 4468.79890404926, 4508.301213311942, 4603.93094103582], "value": 3778.0050467509477, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.3351815899664, 366.06287902968387, 371.1074864949795, 373.67303609341815, 381.16334645453225, 4944.5055494450535, 4998.775055122519, 5043.243513472981, 5070.492950704932, 5081.441041485434, 4936.827211047614, 4963.230699241614, 4986.501349865011, 4987.725675087878, 5005.824796132136, 3774.829802761139, 3905.7427404129494, 3933.724639275252, 3943.5464921533962, 3946.151577410856, 3916.314644937139, 3932.58707835676, 3933.940990885139, 3943.5464921533962, 3952.4664170336923, 3903.180332130242, 3922.941155882664, 3923.8038098095126, 3934.822932968009, 3936.6653834423964, 3699.1319383711325, 3894.80525973703, 3924.8037598120122, 3932.705047121449, 3948.4669569608022, 3415.52182694423, 3416.829158542063, 3417.7607567470286, 3418.077119177833, 3431.365197438482, 4862.197737373329, 4899.216125419951, 4928.162212423897, 4939.728314942677, 4950.079453122675, 4450.130945003107, 4453.710508816916, 4460.643148548141, 4481.244034918534, 4483.6861419700635, 4943.912339285352, 5256.816011439581, 5257.211418287249, 5288.576913846883, 5302.787888484456, 3750.268697603968, 3751.568569614502, 3755.6056614055406, 3770.1894092769912, 3779.622037796226, 5322.308099947021, 5495.175723641406, 5512.33851937766, 5535.612507124503, 5563.221149039097, 3809.866654667129, 3826.579076301605, 3831.1188426662025, 3837.4627552142724, 3842.135519508144, 3880.9818894174873, 3897.8635747748694, 3904.5314562252142, 3905.3165696003084, 3932.410138479194, 4213.157368526307, 4435.423394958635, 4447.444069491314, 4447.844325448594, 4481.843135490308, 5281.049411106038, 5282.445343238982, 5290.31225940633, 5310.309659744285, 5310.973445132827, 1276.489404238312, 1292.0955331267962, 1299.9545015924398, 1301.355828864718, 1306.3598836570202, 821.1337039423465, 860.8794768732382, 876.3033388456192, 889.8309321228945, 893.2630579771715, 4438.644908407319, 4483.843065492692, 4490.348899409567, 4492.865214043584, 4506.977465112654, 5272.2355258487305, 5301.712937978478, 5319.9202011969855, 5356.839294821161, 5386.353637563439, 4268.295731204378, 4574.817007319736, 4578.496365399803, 4584.5186255442695, 4593.425821772278, 2804.233333000197, 2812.690604033555, 2826.745592896649, 2830.462212179679, 2833.943321133566, 4863.927041094386, 4875.731834749123, 4895.412550493964, 4919.77860996256, 5006.6245031622775, 5127.384713834289, 5382.596305277119, 5420.539254163361, 5455.509004189563, 5468.644538105011, 4911.0, 4922.655414121049, 4937.456879743226, 4947.476786838666, 4949.901001980013, 5238.611767881438, 5272.99813035522, 5284.286621306074, 5288.461538461483, 5310.601704872072, 4932.605391568738, 5174.0, 5176.896462070662, 5191.7168336123605, 5209.322788037683, 4917.967226882395, 4953.925691114564, 5080.513045910334, 5084.9745751271, 5113.463086376026, 3816.904577385588, 3858.575556688706, 3862.2275544891695, 3862.5558060823364, 3871.4386413969255, 5417.29355253094, 5419.34967803866, 5440.945590544044, 5442.8639284016635, 5452.91820622683, 4941.382327209099, 5246.632735708464, 5258.6844789311235, 5270.947290527046, 5329.413764485981, 5244.89510209786, 5274.314339135888, 5285.57289531832, 5300.600501583754, 5313.920291195713, 5199.2980947573105, 5229.50319719626, 5237.607179461479, 5241.50205730455, 5241.7379131042535, 5048.469910659328, 5247.527722504993, 5256.553192978639, 5279.0, 5282.336064139784, 5156.0, 5161.845144645667, 5171.3535808024, 5182.740862956913, 5193.948060519347], "value": 3951.002691562824, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.4734418007521, 577.783331250781, 686.1628812848327, 713.0765658472279, 735.554989231515, 11502.712432189199, 12128.454219560119, 12298.815517767242, 12463.18989265697, 12531.060170487204, 11585.42072896356, 12023.75952480952, 12520.749585008294, 12676.429560669769, 12710.68223294418, 802.3701394405393, 820.8973878265217, 823.8022874510104, 823.8599438095508, 825.0470706334194, 795.3120550723611, 810.8378324335127, 820.2084987986592, 820.9917900821005, 822.01358369956, 833.8707500337457, 834.2325060943946, 834.4993004197463, 835.7409203147014, 838.9664413423455, 835.0605568735173, 836.8744688296774, 838.0488145954325, 838.7986883148031, 839.7648658375604, 610.4353473037422, 613.9263288405375, 615.3016326469444, 619.201230412767, 619.2785405003211, 12221.877781222163, 12528.874711252953, 12863.163894346837, 12925.741485170243, 12971.286579238138, 12493.375331233492, 12678.0, 12734.924824056307, 12809.295488748116, 12957.805632915513, 10676.0, 10747.78504429907, 10822.080123189538, 10824.567017319298, 10974.506147223314, 3118.5945827042565, 3134.8432578371217, 3239.1092449576317, 3303.752218583592, 3314.850831712579, 60333.39666603366, 62052.37947620511, 62287.688561557356, 62851.685741571455, 63193.68403157955, 9249.398789078688, 9287.442753434816, 9446.85829712555, 9514.143727064597, 9704.660336888175, 18539.44381668552, 18660.53348666267, 18788.154533045774, 18790.718139227924, 18866.71699924502, 6189.597676151036, 6204.286507051746, 6256.0, 6276.748930042839, 6384.138141350951, 3424.8630054797527, 3445.672661097192, 3468.427709427916, 3491.1151120398645, 3504.737144714106, 2897.0005348154855, 2902.4340253650334, 2942.543905694578, 2945.116465060516, 2949.7197766212175, 486.15210608037694, 492.4140273075029, 495.95784358329223, 506.5770081981513, 510.652756125838, 499.66023104289434, 517.8705323669083, 521.4681025354193, 527.2065541360305, 531.1448567805829, 7728.806779830549, 8012.959935200287, 8107.4324797263625, 8321.708740194184, 8609.139086091342, 572.5333852909948, 611.5871786544064, 612.1552257884091, 615.9630422174683, 617.9196704428396, 3812.256609961031, 3907.238088572701, 3970.305196590585, 3993.5806740292433, 4038.838446462167, 57438.71280643654, 63656.0, 63979.680101599195, 65588.67205664037, 67329.66335168293, 55927.44072559222, 56130.79290459455, 56143.66635169652, 57005.0, 57136.0, 49376.01247975092, 50020.49938501923, 50745.0, 51486.48513514817, 52059.219111713355, 7369.77890663281, 7587.962060189664, 7670.27132422419, 7732.420068494773, 7854.72508462224, 34902.476462853585, 35043.29913401667, 35690.821545892104, 36385.27229455343, 36490.27019459649, 39678.60321396864, 39795.60204398035, 41372.0, 41951.7902410486, 42298.7885060585, 9514.191293739832, 9629.037096290314, 9731.172850307803, 10218.7092471106, 11196.94401527987, 10395.896041039492, 10535.78928421412, 12434.0, 12920.22478651284, 13451.125676830974, 11000.449977501254, 11596.884031159918, 11977.461014254553, 12042.21725587834, 12098.697532561757, 11229.55081796719, 11466.312021278416, 12012.159148859497, 12108.939455302667, 12435.875641243472, 11531.596394126165, 11570.421478925851, 11775.646730598095, 11844.644660660195, 12001.339926303788, 8222.794430139294, 8709.346798989975, 8726.214640682369, 8730.738077857675, 8884.511351875708, 7987.361011119213, 8526.275266602408, 8905.154010369004, 9329.953350233205, 9593.664221752206], "value": 6088.51773577113, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 116.0, 116.0, 115.0, 114.0, 115.0, 115.0, 115.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 127.0, 128.0, 171.0, 133.0, 133.0, 134.0, 127.0, 150.0, 130.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 169.0, 167.0, 174.0, 163.0, 165.0, 164.0, 165.0, 163.0], "value": 166.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 123.0, 77.0, 81.0, 77.0, 78.0, 95.0, 79.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 108.0, 84.0, 84.0, 85.0, 105.0, 84.0, 85.0, 115.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 152.0, 151.0, 149.0, 150.0, 152.0, 152.0, 151.0, 155.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 97.0, 96.0, 96.0, 96.0, 95.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 65.0, 68.0, 70.0, 70.0, 67.0, 67.0, 68.0, 66.0], "value": 67.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 67.0, 66.0, 65.0, 65.0, 65.0, 65.0, 69.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 89.0, 90.0, 91.0, 89.0, 89.0, 91.0, 88.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 140.0, 143.0, 138.0, 146.0, 141.0, 148.0, 137.0, 187.0, 139.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 190.0, 183.0, 188.0, 191.0, 185.0, 183.0, 187.0, 180.0, 185.0], "value": 186.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 69.0, 70.0, 72.0, 70.0, 71.0, 70.0, 70.0, 76.0, 74.0], "value": 71.7, "unit": "score"}]}]} 20:42:27 INFO - Return code: 0 20:42:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:42:27 ERROR - # TBPL SUCCESS # 20:42:27 INFO - Running post-action listener: _resource_record_post_action 20:42:27 INFO - [mozharness: 2017-02-23 04:42:27.984353Z] Finished run-tests step (success) 20:42:27 INFO - Running post-run listener: _resource_record_post_run 20:42:28 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 360157184; Read time: 148; Write time: 215500 20:42:28 INFO - TinderboxPrint: CPU usage
13.5% 20:42:28 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 148 20:42:28 INFO - TinderboxPrint: I/O write bytes / time
360,157,184 / 215,500 20:42:28 INFO - TinderboxPrint: CPU idle
8,290.0 (86.5%) 20:42:28 INFO - TinderboxPrint: CPU user
1,236.0 (12.9%) 20:42:28 INFO - TinderboxPrint: Swap in / out
0 / 0 20:42:28 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105193472; Read time: 0; Write time: 73636 20:42:28 INFO - run-tests - Wall time: 1186s; CPU: 14.0%; Read bytes: 4096; Write bytes: 254963712; Read time: 148; Write time: 141864 20:42:28 INFO - Running post-run listener: _upload_blobber_files 20:42:28 INFO - Blob upload gear active. 20:42:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:42:28 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 20:42:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:42:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:42:29 INFO - (blobuploader) - INFO - Open directory for files ... 20:42:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:42:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:32 INFO - (blobuploader) - INFO - Done attempting. 20:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:42:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:33 INFO - (blobuploader) - INFO - Done attempting. 20:42:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:42:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:42:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:34 INFO - (blobuploader) - INFO - Done attempting. 20:42:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:42:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:42:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:35 INFO - (blobuploader) - INFO - Done attempting. 20:42:35 INFO - (blobuploader) - INFO - Iteration through files over. 20:42:35 INFO - Return code: 0 20:42:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:42:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:42:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7708556f5594558f3979f2b9fb1907b1e5d95f7653a9f82eab7ad80ef8b529ba74d4cf61b78b9f18591c88e7c39fb9e4e8ce4bdb0aa0b44b1b544e6760fd286d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c6c2dcfdf63c8a25c05135ef374148fb20971fbeeaf11becc4a17bc0f47c67f1fab029df576e95036f45429056a960ac909a41cfa3a2ecce037f4b824ea072ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dca9791ee085b6752cdaeed587447e0eb8fe16852e8b8c0d4f6f1533baf319478a6a271803c953e27d451b0705d0c935ebdbf31bebd0dcaf01df6317663d8f06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/973a392af9c881f0284e5c38f7b7e58c75aef0944f73c0cf6f8b7b5a96b1292ae18eac2320f82996204cf0a826fa1ebe6e299f6ecbdfce553c516ca2e98801b6"} 20:42:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:42:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:42:35 INFO - Contents: 20:42:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7708556f5594558f3979f2b9fb1907b1e5d95f7653a9f82eab7ad80ef8b529ba74d4cf61b78b9f18591c88e7c39fb9e4e8ce4bdb0aa0b44b1b544e6760fd286d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c6c2dcfdf63c8a25c05135ef374148fb20971fbeeaf11becc4a17bc0f47c67f1fab029df576e95036f45429056a960ac909a41cfa3a2ecce037f4b824ea072ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dca9791ee085b6752cdaeed587447e0eb8fe16852e8b8c0d4f6f1533baf319478a6a271803c953e27d451b0705d0c935ebdbf31bebd0dcaf01df6317663d8f06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/973a392af9c881f0284e5c38f7b7e58c75aef0944f73c0cf6f8b7b5a96b1292ae18eac2320f82996204cf0a826fa1ebe6e299f6ecbdfce553c516ca2e98801b6"} 20:42:35 INFO - Running post-run listener: copy_logs_to_upload_dir 20:42:35 INFO - Copying logs to upload dir... 20:42:35 INFO - mkdir: /builds/slave/test/build/upload/logs 20:42:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.148232 ========= master_lag: 2.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-22 20:42:36.960006) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:36.968685) ========= 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=199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7708556f5594558f3979f2b9fb1907b1e5d95f7653a9f82eab7ad80ef8b529ba74d4cf61b78b9f18591c88e7c39fb9e4e8ce4bdb0aa0b44b1b544e6760fd286d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c6c2dcfdf63c8a25c05135ef374148fb20971fbeeaf11becc4a17bc0f47c67f1fab029df576e95036f45429056a960ac909a41cfa3a2ecce037f4b824ea072ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dca9791ee085b6752cdaeed587447e0eb8fe16852e8b8c0d4f6f1533baf319478a6a271803c953e27d451b0705d0c935ebdbf31bebd0dcaf01df6317663d8f06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/973a392af9c881f0284e5c38f7b7e58c75aef0944f73c0cf6f8b7b5a96b1292ae18eac2320f82996204cf0a826fa1ebe6e299f6ecbdfce553c516ca2e98801b6"} build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011356 build_url: 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7708556f5594558f3979f2b9fb1907b1e5d95f7653a9f82eab7ad80ef8b529ba74d4cf61b78b9f18591c88e7c39fb9e4e8ce4bdb0aa0b44b1b544e6760fd286d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c6c2dcfdf63c8a25c05135ef374148fb20971fbeeaf11becc4a17bc0f47c67f1fab029df576e95036f45429056a960ac909a41cfa3a2ecce037f4b824ea072ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dca9791ee085b6752cdaeed587447e0eb8fe16852e8b8c0d4f6f1533baf319478a6a271803c953e27d451b0705d0c935ebdbf31bebd0dcaf01df6317663d8f06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/973a392af9c881f0284e5c38f7b7e58c75aef0944f73c0cf6f8b7b5a96b1292ae18eac2320f82996204cf0a826fa1ebe6e299f6ecbdfce553c516ca2e98801b6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:37.009200) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:37.009623) ========= 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=199fb4c72b90437b990b419b000001c5-1487821243.935945-1964979181 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004521 ========= master_lag: 0.45 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:37.459586) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:37.459957) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:37.460696) ========= ========= Total master_lag: 2.70 =========