builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-050 starttime: 1487369579.5 results: success (0) revision: 0930fdc4cf8e0116e19577005f57f7ec12336ced ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:12:59.499204) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:12:59.499674) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:12:59.499948) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:12:59.515845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:12:59.516104) ========= 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=6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 14:12:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.7M=0s 2017-02-17 14:12:59 (45.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132957 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:12:59.662207) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:12:59.662530) ========= 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=6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017551 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:12:59.712168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:12:59.712512) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0930fdc4cf8e0116e19577005f57f7ec12336ced --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 0930fdc4cf8e0116e19577005f57f7ec12336ced --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=6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 14:12:59,800 truncating revision to first 12 chars 2017-02-17 14:12:59,800 Setting DEBUG logging. 2017-02-17 14:12:59,800 attempt 1/10 2017-02-17 14:12:59,800 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0930fdc4cf8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 14:13:00,271 unpacking tar archive at: mozilla-central-0930fdc4cf8e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.748977 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:13:00.474257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:13:00.474590) ========= script_repo_revision: 0930fdc4cf8e0116e19577005f57f7ec12336ced ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:13:00.474951) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:13:00.475217) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:13:00.483690) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-17 14:13:00.483962) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:13:00 INFO - MultiFileLogger online at 20170217 14:13:00 in /builds/slave/test 14:13:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 14:13:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:13:00 INFO - {'append_to_log': False, 14:13:00 INFO - 'base_work_dir': '/builds/slave/test', 14:13:00 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:13:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:13:00 INFO - 'branch': 'Firefox-Non-PGO', 14:13:00 INFO - 'buildbot_json_path': 'buildprops.json', 14:13:00 INFO - 'config_files': ('talos/linux_config.py',), 14:13:00 INFO - 'default_actions': ('clobber', 14:13:00 INFO - 'read-buildbot-config', 14:13:00 INFO - 'download-and-extract', 14:13:00 INFO - 'populate-webroot', 14:13:00 INFO - 'create-virtualenv', 14:13:00 INFO - 'install', 14:13:00 INFO - 'run-tests'), 14:13:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:13:00 INFO - 'download_minidump_stackwalk': True, 14:13:00 INFO - 'download_symbols': 'ondemand', 14:13:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:13:00 INFO - 'tooltool.py': '/tools/tooltool.py', 14:13:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:13:00 INFO - '/tools/misc-python/virtualenv.py')}, 14:13:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:13:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:13:00 INFO - 'installer_path': 'installer.exe', 14:13:00 INFO - 'log_level': 'info', 14:13:00 INFO - 'log_name': 'talos', 14:13:00 INFO - 'log_to_console': True, 14:13:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:13:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:13:00 INFO - 'opt_config_files': (), 14:13:00 INFO - 'pip_index': False, 14:13:00 INFO - 'sps_profile': False, 14:13:00 INFO - 'sps_profile_interval': 0, 14:13:00 INFO - 'suite': 'dromaeojs', 14:13:00 INFO - 'system_bits': '32', 14:13:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:13:00 INFO - 'title': 'talos-linux64-ix-050', 14:13:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:13:00 INFO - 'use_talos_json': True, 14:13:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:13:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:13:00 INFO - 'work_dir': 'build'} 14:13:00 INFO - [mozharness: 2017-02-17 22:13:00.676056Z] Running clobber step. 14:13:00 INFO - Running pre-action listener: _resource_record_pre_action 14:13:00 INFO - Running main action method: clobber 14:13:00 INFO - rmtree: /builds/slave/test/build 14:13:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:13:01 INFO - Running post-action listener: _resource_record_post_action 14:13:01 INFO - [mozharness: 2017-02-17 22:13:01.588708Z] Finished clobber step (success) 14:13:01 INFO - [mozharness: 2017-02-17 22:13:01.588809Z] Running read-buildbot-config step. 14:13:01 INFO - Running pre-action listener: _resource_record_pre_action 14:13:01 INFO - Running main action method: read_buildbot_config 14:13:01 INFO - Using buildbot properties: 14:13:01 INFO - { 14:13:01 INFO - "project": "", 14:13:01 INFO - "product": "firefox", 14:13:01 INFO - "who": "kwierso@gmail.com", 14:13:01 INFO - "installer_path": "public/build/target.tar.bz2", 14:13:01 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 14:13:01 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:13:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 14:13:01 INFO - "stage_platform": "linux64", 14:13:01 INFO - "basedir": "/builds/slave/test", 14:13:01 INFO - "buildnumber": 454, 14:13:01 INFO - "platform": "ubuntu64_hw", 14:13:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:13:01 INFO - "slavebuilddir": "test", 14:13:01 INFO - "taskId": "EWgrezUmRRioj-yslOvm5Q", 14:13:01 INFO - "branch": "mozilla-central", 14:13:01 INFO - "script_repo_revision": "production", 14:13:01 INFO - "revision": "0930fdc4cf8e0116e19577005f57f7ec12336ced", 14:13:01 INFO - "slavename": "talos-linux64-ix-050", 14:13:01 INFO - "repo_path": "mozilla-central" 14:13:01 INFO - } 14:13:01 INFO - Finding installer, test and symbols from parent task. 14:13:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EWgrezUmRRioj-yslOvm5Q'}, attempt #1 14:13:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EWgrezUmRRioj-yslOvm5Q'}, attempt #1 14:13:02 INFO - Task dependencies: MobKWfk2RVS1PNGQwQj7zg 14:13:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg'}, attempt #1 14:13:03 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 14:13:03 INFO - Set test_url: https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/test_packages.json 14:13:03 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.crashreporter-symbols.zip 14:13:03 INFO - Running post-action listener: _resource_record_post_action 14:13:03 INFO - [mozharness: 2017-02-17 22:13:03.090100Z] Finished read-buildbot-config step (success) 14:13:03 INFO - [mozharness: 2017-02-17 22:13:03.090321Z] Running download-and-extract step. 14:13:03 INFO - Running pre-action listener: _resource_record_pre_action 14:13:03 INFO - Running main action method: download_and_extract 14:13:03 INFO - mkdir: /builds/slave/test/build/tests 14:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:03 INFO - trying https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.test_packages.json 14:13:03 INFO - Downloading https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:13:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:13:03 INFO - Downloaded 896 bytes. 14:13:03 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:13:03 INFO - Using the following test package requirements: 14:13:03 INFO - {u'common': [u'target.common.tests.zip'], 14:13:03 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:13:03 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:13:03 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:13:03 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:13:03 INFO - u'mozbase': [u'target.common.tests.zip'], 14:13:03 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:13:03 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:13:03 INFO - u'web-platform': [u'target.common.tests.zip', 14:13:03 INFO - u'target.web-platform.tests.zip'], 14:13:03 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:13:03 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:13:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip 14:13:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:13:03 INFO - Fetch https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip into memory 14:13:04 INFO - Expected file size: 57145682 14:13:04 INFO - Obtained file size: 57145682 14:13:14 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:13:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip 14:13:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:13:14 INFO - Fetch https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.common.tests.zip into memory 14:13:15 INFO - Expected file size: 57145682 14:13:15 INFO - Obtained file size: 57145682 14:13:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.talos.tests.zip 14:13:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:13:26 INFO - Fetch https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.talos.tests.zip into memory 14:13:26 INFO - Expected file size: 14403647 14:13:26 INFO - Obtained file size: 14403647 14:13:27 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')]} 14:13:27 INFO - trying https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 14:13:27 INFO - Downloading https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:13:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:13:28 INFO - Downloaded 61400565 bytes. 14:13:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 14:13:28 INFO - mkdir: /builds/slave/test/properties 14:13:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:13:28 INFO - Writing to file /builds/slave/test/properties/build_url 14:13:28 INFO - Contents: 14:13:28 INFO - build_url:https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 14:13:28 INFO - Running post-action listener: _resource_record_post_action 14:13:28 INFO - Running post-action listener: set_extra_try_arguments 14:13:28 INFO - [mozharness: 2017-02-17 22:13:28.359981Z] Finished download-and-extract step (success) 14:13:28 INFO - [mozharness: 2017-02-17 22:13:28.360077Z] Running populate-webroot step. 14:13:28 INFO - Running pre-action listener: _resource_record_pre_action 14:13:28 INFO - Running main action method: populate_webroot 14:13:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:13:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:13:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:13:28 INFO - '--tppagecycles', 14:13:28 INFO - '7'], 14:13:28 INFO - 'tests': ['tsvgm']}}, 14:13:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:13:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:13:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:13:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:13:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:13:28 INFO - 'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:13:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:13:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:13:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:13:28 INFO - 'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['damp', 'tps']}, 14:13:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:13:28 INFO - 'tests': ['damp', 'tps']}, 14:13:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['dromaeo_dom']}, 14:13:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:13:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['basic_compositor_video']}, 14:13:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:13:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['a11yr', 14:13:28 INFO - 'ts_paint', 14:13:28 INFO - 'tpaint', 14:13:28 INFO - 'sessionrestore', 14:13:28 INFO - 'sessionrestore_no_auto_restore', 14:13:28 INFO - 'tabpaint']}, 14:13:28 INFO - 'other-e10s': {'tests': ['a11yr', 14:13:28 INFO - 'ts_paint', 14:13:28 INFO - 'tpaint', 14:13:28 INFO - 'sessionrestore', 14:13:28 INFO - 'sessionrestore_no_auto_restore', 14:13:28 INFO - 'tabpaint']}, 14:13:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:13:28 INFO - 'ts_paint', 14:13:28 INFO - 'tpaint', 14:13:28 INFO - 'sessionrestore', 14:13:28 INFO - 'sessionrestore_no_auto_restore', 14:13:28 INFO - 'tabpaint']}, 14:13:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:13:28 INFO - 'ts_paint', 14:13:28 INFO - 'tpaint', 14:13:28 INFO - 'sessionrestore', 14:13:28 INFO - 'sessionrestore_no_auto_restore', 14:13:28 INFO - 'tabpaint']}, 14:13:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['a11yr', 14:13:28 INFO - 'ts_paint', 14:13:28 INFO - 'tpaint', 14:13:28 INFO - 'sessionrestore', 14:13:28 INFO - 'sessionrestore_no_auto_restore', 14:13:28 INFO - 'tabpaint']}, 14:13:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['a11yr', 14:13:28 INFO - 'ts_paint', 14:13:28 INFO - 'tpaint', 14:13:28 INFO - 'sessionrestore', 14:13:28 INFO - 'sessionrestore_no_auto_restore', 14:13:28 INFO - 'tabpaint']}, 14:13:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['tsvgx', 14:13:28 INFO - 'tsvgr_opacity', 14:13:28 INFO - 'tart', 14:13:28 INFO - 'tscrollx', 14:13:28 INFO - 'cart', 14:13:28 INFO - 'tsvg_static']}, 14:13:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:13:28 INFO - 'tsvgr_opacity', 14:13:28 INFO - 'tart', 14:13:28 INFO - 'tscrollx', 14:13:28 INFO - 'cart', 14:13:28 INFO - 'tsvg_static']}, 14:13:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:13:28 INFO - 'talos_options': ['--disable-e10s'], 14:13:28 INFO - 'tests': ['tp5o']}, 14:13:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:13:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:13:28 INFO - 'talos_options': ['--disable-e10s', 14:13:28 INFO - '--xperf_path', 14:13:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:13:28 INFO - 'tests': ['tp5n']}, 14:13:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:13:28 INFO - 'talos_options': ['--xperf_path', 14:13:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:13:28 INFO - 'tests': ['tp5n']}}, 14:13:28 INFO - 'talos.zip': {'path': '', 14:13:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:13:28 INFO - Running post-action listener: _resource_record_post_action 14:13:28 INFO - [mozharness: 2017-02-17 22:13:28.373132Z] Finished populate-webroot step (success) 14:13:28 INFO - [mozharness: 2017-02-17 22:13:28.373227Z] Running create-virtualenv step. 14:13:28 INFO - Running pre-action listener: _resource_record_pre_action 14:13:28 INFO - Running main action method: create_virtualenv 14:13:28 INFO - Creating virtualenv /builds/slave/test/build/venv 14:13:28 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 14:13:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:13:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:13:28 INFO - Using real prefix '/usr' 14:13:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:13:28 INFO - Installing distribute.............................................................................................................................................................................................done. 14:13:30 INFO - Installing pip.................done. 14:13:30 INFO - Return code: 0 14:13:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:13:31 INFO - Reading from file tmpfile_stdout 14:13:31 INFO - Output received: 14:13:31 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) 14:13:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:31 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:31 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 14:13:31 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 14:13:31 INFO - Using env: {'DISPLAY': ':0', 14:13:31 INFO - 'HOME': '/home/cltbld', 14:13:31 INFO - 'LANG': 'en_US.UTF-8', 14:13:31 INFO - 'LANGUAGE': 'en_US:en', 14:13:31 INFO - 'LOGNAME': 'cltbld', 14:13:31 INFO - 'MAIL': '/var/mail/cltbld', 14:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:31 INFO - 'MOZ_NO_REMOTE': '1', 14:13:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:31 INFO - 'NO_EM_RESTART': '1', 14:13:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:31 INFO - 'PWD': '/builds/slave/test', 14:13:31 INFO - 'SHELL': '/bin/bash', 14:13:31 INFO - 'SHLVL': '1', 14:13:31 INFO - 'TERM': 'linux', 14:13:31 INFO - 'TMOUT': '86400', 14:13:31 INFO - 'USER': 'cltbld', 14:13:31 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:31 INFO - '_': '/tools/buildbot/bin/python'} 14:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:31 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 14:13:31 INFO - Cleaning up... 14:13:31 INFO - Return code: 0 14:13:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:31 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:31 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 14:13:31 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 14:13:31 INFO - Using env: {'DISPLAY': ':0', 14:13:31 INFO - 'HOME': '/home/cltbld', 14:13:31 INFO - 'LANG': 'en_US.UTF-8', 14:13:31 INFO - 'LANGUAGE': 'en_US:en', 14:13:31 INFO - 'LOGNAME': 'cltbld', 14:13:31 INFO - 'MAIL': '/var/mail/cltbld', 14:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:31 INFO - 'MOZ_NO_REMOTE': '1', 14:13:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:31 INFO - 'NO_EM_RESTART': '1', 14:13:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:31 INFO - 'PWD': '/builds/slave/test', 14:13:31 INFO - 'SHELL': '/bin/bash', 14:13:31 INFO - 'SHLVL': '1', 14:13:31 INFO - 'TERM': 'linux', 14:13:31 INFO - 'TMOUT': '86400', 14:13:31 INFO - 'USER': 'cltbld', 14:13:31 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:31 INFO - '_': '/tools/buildbot/bin/python'} 14:13:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:31 INFO - Downloading/unpacking psutil>=3.1.1 14:13:31 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 14:13:31 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 14:13:31 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 14:13:31 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 14:13:31 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 14:13:31 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 14:13:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:13:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:13:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:13:33 INFO - Installing collected packages: psutil 14:13:33 INFO - Running setup.py install for psutil 14:13:33 INFO - building 'psutil._psutil_linux' extension 14:13:33 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 14:13:33 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 14:13:33 INFO - building 'psutil._psutil_posix' extension 14:13:33 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 14:13:34 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 14:13:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:13:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:13:34 INFO - Successfully installed psutil 14:13:34 INFO - Cleaning up... 14:13:34 INFO - Return code: 0 14:13:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:13:34 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')]} 14:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:34 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')]} 14:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:34 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:34 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 14:13:34 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 14:13:34 INFO - Using env: {'DISPLAY': ':0', 14:13:34 INFO - 'HOME': '/home/cltbld', 14:13:34 INFO - 'LANG': 'en_US.UTF-8', 14:13:34 INFO - 'LANGUAGE': 'en_US:en', 14:13:34 INFO - 'LOGNAME': 'cltbld', 14:13:34 INFO - 'MAIL': '/var/mail/cltbld', 14:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:34 INFO - 'MOZ_NO_REMOTE': '1', 14:13:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:34 INFO - 'NO_EM_RESTART': '1', 14:13:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:34 INFO - 'PWD': '/builds/slave/test', 14:13:34 INFO - 'SHELL': '/bin/bash', 14:13:34 INFO - 'SHLVL': '1', 14:13:34 INFO - 'TERM': 'linux', 14:13:34 INFO - 'TMOUT': '86400', 14:13:34 INFO - 'USER': 'cltbld', 14:13:34 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:34 INFO - '_': '/tools/buildbot/bin/python'} 14:13:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:13:34 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 14:13:34 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 14:13:34 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 14:13:34 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 14:13:34 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 14:13:34 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 14:13:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:13:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:13:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:13:36 INFO - Installing collected packages: mozsystemmonitor 14:13:36 INFO - Running setup.py install for mozsystemmonitor 14:13:36 INFO - Successfully installed mozsystemmonitor 14:13:36 INFO - Cleaning up... 14:13:36 INFO - Return code: 0 14:13:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:36 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:36 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 14:13:36 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 14:13:36 INFO - Using env: {'DISPLAY': ':0', 14:13:36 INFO - 'HOME': '/home/cltbld', 14:13:36 INFO - 'LANG': 'en_US.UTF-8', 14:13:36 INFO - 'LANGUAGE': 'en_US:en', 14:13:36 INFO - 'LOGNAME': 'cltbld', 14:13:36 INFO - 'MAIL': '/var/mail/cltbld', 14:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:36 INFO - 'MOZ_NO_REMOTE': '1', 14:13:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:36 INFO - 'NO_EM_RESTART': '1', 14:13:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:36 INFO - 'PWD': '/builds/slave/test', 14:13:36 INFO - 'SHELL': '/bin/bash', 14:13:36 INFO - 'SHLVL': '1', 14:13:36 INFO - 'TERM': 'linux', 14:13:36 INFO - 'TMOUT': '86400', 14:13:36 INFO - 'USER': 'cltbld', 14:13:36 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:36 INFO - '_': '/tools/buildbot/bin/python'} 14:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:36 INFO - Downloading/unpacking jsonschema==2.5.1 14:13:36 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 14:13:36 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 14:13:36 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 14:13:36 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 14:13:36 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 14:13:36 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 14:13:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:13:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:13:38 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 14:13:38 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 14:13:38 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 14:13:38 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 14:13:38 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 14:13:38 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 14:13:39 INFO - Downloading functools32-3.2.3-2.tar.gz 14:13:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:13:39 INFO - warning: no files found matching '*.txt' 14:13:39 INFO - no previously-included directories found matching 'build' 14:13:39 INFO - no previously-included directories found matching 'dist' 14:13:39 INFO - no previously-included directories found matching '.git*' 14:13:39 INFO - Installing collected packages: jsonschema, functools32 14:13:39 INFO - Running setup.py install for functools32 14:13:39 INFO - warning: no files found matching '*.txt' 14:13:39 INFO - no previously-included directories found matching 'build' 14:13:39 INFO - no previously-included directories found matching 'dist' 14:13:39 INFO - no previously-included directories found matching '.git*' 14:13:39 INFO - Successfully installed jsonschema functools32 14:13:39 INFO - Cleaning up... 14:13:39 INFO - Return code: 0 14:13:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:13:39 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')]} 14:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:39 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')]} 14:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:39 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:39 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 14:13:39 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 14:13:39 INFO - Using env: {'DISPLAY': ':0', 14:13:39 INFO - 'HOME': '/home/cltbld', 14:13:39 INFO - 'LANG': 'en_US.UTF-8', 14:13:39 INFO - 'LANGUAGE': 'en_US:en', 14:13:39 INFO - 'LOGNAME': 'cltbld', 14:13:39 INFO - 'MAIL': '/var/mail/cltbld', 14:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:39 INFO - 'MOZ_NO_REMOTE': '1', 14:13:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:39 INFO - 'NO_EM_RESTART': '1', 14:13:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:39 INFO - 'PWD': '/builds/slave/test', 14:13:39 INFO - 'SHELL': '/bin/bash', 14:13:39 INFO - 'SHLVL': '1', 14:13:39 INFO - 'TERM': 'linux', 14:13:39 INFO - 'TMOUT': '86400', 14:13:39 INFO - 'USER': 'cltbld', 14:13:39 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:39 INFO - '_': '/tools/buildbot/bin/python'} 14:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:13:39 INFO - Cleaning up... 14:13:39 INFO - Return code: 0 14:13:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:13:39 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')]} 14:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:39 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')]} 14:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:39 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:39 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 14:13:39 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 14:13:39 INFO - Using env: {'DISPLAY': ':0', 14:13:39 INFO - 'HOME': '/home/cltbld', 14:13:39 INFO - 'LANG': 'en_US.UTF-8', 14:13:39 INFO - 'LANGUAGE': 'en_US:en', 14:13:39 INFO - 'LOGNAME': 'cltbld', 14:13:39 INFO - 'MAIL': '/var/mail/cltbld', 14:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:39 INFO - 'MOZ_NO_REMOTE': '1', 14:13:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:39 INFO - 'NO_EM_RESTART': '1', 14:13:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:39 INFO - 'PWD': '/builds/slave/test', 14:13:39 INFO - 'SHELL': '/bin/bash', 14:13:39 INFO - 'SHLVL': '1', 14:13:39 INFO - 'TERM': 'linux', 14:13:39 INFO - 'TMOUT': '86400', 14:13:39 INFO - 'USER': 'cltbld', 14:13:39 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:39 INFO - '_': '/tools/buildbot/bin/python'} 14:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:39 INFO - Downloading/unpacking blobuploader==1.2.4 14:13:39 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 14:13:39 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 14:13:39 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 14:13:39 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 14:13:39 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 14:13:39 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 14:13:41 INFO - Downloading blobuploader-1.2.4.tar.gz 14:13:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:13:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:13:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:13:42 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 14:13:42 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 14:13:42 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 14:13:42 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 14:13:42 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 14:13:42 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 14:13:42 INFO - Downloading docopt-0.6.1.tar.gz 14:13:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:13:42 INFO - Installing collected packages: blobuploader, requests, docopt 14:13:42 INFO - Running setup.py install for blobuploader 14:13:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:13:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:13:42 INFO - Running setup.py install for requests 14:13:43 INFO - Running setup.py install for docopt 14:13:43 INFO - Successfully installed blobuploader requests docopt 14:13:43 INFO - Cleaning up... 14:13:43 INFO - Return code: 0 14:13:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:43 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:43 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 14:13:43 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 14:13:43 INFO - Using env: {'DISPLAY': ':0', 14:13:43 INFO - 'HOME': '/home/cltbld', 14:13:43 INFO - 'LANG': 'en_US.UTF-8', 14:13:43 INFO - 'LANGUAGE': 'en_US:en', 14:13:43 INFO - 'LOGNAME': 'cltbld', 14:13:43 INFO - 'MAIL': '/var/mail/cltbld', 14:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:43 INFO - 'MOZ_NO_REMOTE': '1', 14:13:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:43 INFO - 'NO_EM_RESTART': '1', 14:13:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:43 INFO - 'PWD': '/builds/slave/test', 14:13:43 INFO - 'SHELL': '/bin/bash', 14:13:43 INFO - 'SHLVL': '1', 14:13:43 INFO - 'TERM': 'linux', 14:13:43 INFO - 'TMOUT': '86400', 14:13:43 INFO - 'USER': 'cltbld', 14:13:43 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:43 INFO - '_': '/tools/buildbot/bin/python'} 14:13:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:13:43 INFO - Running setup.py (path:/tmp/pip-qyXPf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:13:43 INFO - Running setup.py (path:/tmp/pip-JdYWbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:13:43 INFO - Running setup.py (path:/tmp/pip-bZEOo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:13:43 INFO - Running setup.py (path:/tmp/pip-J_BQIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:13:43 INFO - Running setup.py (path:/tmp/pip-bbI9vx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:13:43 INFO - Running setup.py (path:/tmp/pip-gV6PU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:13:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:13:43 INFO - Running setup.py (path:/tmp/pip-UnvvTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:13:44 INFO - Running setup.py (path:/tmp/pip-B07MN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:13:44 INFO - Running setup.py (path:/tmp/pip-Mnm1TP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:13:44 INFO - Running setup.py (path:/tmp/pip-LVFt9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:13:44 INFO - Running setup.py (path:/tmp/pip-p5NcEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:13:44 INFO - Running setup.py (path:/tmp/pip-_DHas3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:13:44 INFO - Running setup.py (path:/tmp/pip-fW8gwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:13:44 INFO - Running setup.py (path:/tmp/pip-VeUDwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:44 INFO - Running setup.py (path:/tmp/pip-oTCtcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:13:44 INFO - Running setup.py (path:/tmp/pip-pSd2l2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:13:44 INFO - Running setup.py (path:/tmp/pip-aNH6yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:13:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:13:44 INFO - Running setup.py install for manifestparser 14:13:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:13:44 INFO - Running setup.py install for mozcrash 14:13:44 INFO - Running setup.py install for mozdebug 14:13:45 INFO - Running setup.py install for mozdevice 14:13:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Running setup.py install for mozfile 14:13:45 INFO - Running setup.py install for mozhttpd 14:13:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Running setup.py install for mozinfo 14:13:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Running setup.py install for mozInstall 14:13:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Running setup.py install for mozleak 14:13:45 INFO - Running setup.py install for mozlog 14:13:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Running setup.py install for moznetwork 14:13:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:13:45 INFO - Running setup.py install for mozprocess 14:13:46 INFO - Running setup.py install for mozprofile 14:13:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:13:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:13:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:13:46 INFO - Running setup.py install for mozrunner 14:13:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:13:46 INFO - Running setup.py install for mozscreenshot 14:13:46 INFO - Running setup.py install for moztest 14:13:46 INFO - Running setup.py install for mozversion 14:13:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:13:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:13:46 INFO - Cleaning up... 14:13:46 INFO - Return code: 0 14:13:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:46 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:46 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 14:13:46 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 14:13:46 INFO - Using env: {'DISPLAY': ':0', 14:13:46 INFO - 'HOME': '/home/cltbld', 14:13:46 INFO - 'LANG': 'en_US.UTF-8', 14:13:46 INFO - 'LANGUAGE': 'en_US:en', 14:13:46 INFO - 'LOGNAME': 'cltbld', 14:13:46 INFO - 'MAIL': '/var/mail/cltbld', 14:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:46 INFO - 'MOZ_NO_REMOTE': '1', 14:13:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:46 INFO - 'NO_EM_RESTART': '1', 14:13:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:46 INFO - 'PWD': '/builds/slave/test', 14:13:46 INFO - 'SHELL': '/bin/bash', 14:13:46 INFO - 'SHLVL': '1', 14:13:46 INFO - 'TERM': 'linux', 14:13:46 INFO - 'TMOUT': '86400', 14:13:46 INFO - 'USER': 'cltbld', 14:13:46 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:46 INFO - '_': '/tools/buildbot/bin/python'} 14:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:13:46 INFO - Running setup.py (path:/tmp/pip-c4OLEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:13:46 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)) 14:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:13:46 INFO - Running setup.py (path:/tmp/pip-hKvTU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:13:46 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)) 14:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:13:46 INFO - Running setup.py (path:/tmp/pip-mSZQjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:13:47 INFO - Running setup.py (path:/tmp/pip-Cztwkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:13:47 INFO - Running setup.py (path:/tmp/pip-qeREV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:13:47 INFO - Running setup.py (path:/tmp/pip-gIrybZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:13:47 INFO - Running setup.py (path:/tmp/pip-s8g_si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:13:47 INFO - Running setup.py (path:/tmp/pip-rpHn8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:13:47 INFO - Running setup.py (path:/tmp/pip-aNIyHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:13:47 INFO - Running setup.py (path:/tmp/pip-ZIoxcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:13:47 INFO - Running setup.py (path:/tmp/pip-_UmWg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:13:47 INFO - Running setup.py (path:/tmp/pip-79qA_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:13:47 INFO - Running setup.py (path:/tmp/pip-xuThwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:13:47 INFO - Running setup.py (path:/tmp/pip-gx3a4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:47 INFO - Running setup.py (path:/tmp/pip-RSOUMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:13:47 INFO - Running setup.py (path:/tmp/pip-pu1iOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:13:47 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)) 14:13:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:13:47 INFO - Running setup.py (path:/tmp/pip-RhNJxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:13:48 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)) 14:13:48 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)) 14:13:48 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)) 14:13:48 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)) 14:13:48 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)) 14:13:48 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)) 14:13:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:13:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:13:50 INFO - Downloading blessings-1.6.tar.gz 14:13:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:13:50 INFO - Installing collected packages: blessings 14:13:50 INFO - Running setup.py install for blessings 14:13:50 INFO - Successfully installed blessings 14:13:50 INFO - Cleaning up... 14:13:50 INFO - Return code: 0 14:13:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:13:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:50 INFO - Reading from file tmpfile_stdout 14:13:50 INFO - Current package versions: 14:13:50 INFO - argparse == 1.2.1 14:13:50 INFO - blessings == 1.6 14:13:50 INFO - blobuploader == 1.2.4 14:13:50 INFO - docopt == 0.6.1 14:13:50 INFO - functools32 == 3.2.3-2 14:13:50 INFO - jsonschema == 2.5.1 14:13:50 INFO - manifestparser == 1.1 14:13:50 INFO - mozInstall == 1.12 14:13:50 INFO - mozcrash == 1.0 14:13:50 INFO - mozdebug == 0.1 14:13:50 INFO - mozdevice == 0.49 14:13:50 INFO - mozfile == 1.2 14:13:50 INFO - mozhttpd == 0.7 14:13:50 INFO - mozinfo == 0.9 14:13:50 INFO - mozleak == 0.1 14:13:50 INFO - mozlog == 3.4 14:13:50 INFO - moznetwork == 0.27 14:13:50 INFO - mozprocess == 0.25 14:13:50 INFO - mozprofile == 0.28 14:13:50 INFO - mozrunner == 6.13 14:13:50 INFO - mozscreenshot == 0.1 14:13:50 INFO - mozsystemmonitor == 0.3 14:13:50 INFO - moztest == 0.8 14:13:50 INFO - mozversion == 1.4 14:13:50 INFO - psutil == 3.1.1 14:13:50 INFO - requests == 1.2.3 14:13:50 INFO - wsgiref == 0.1.2 14:13:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:13:50 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')]} 14:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:50 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')]} 14:13:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:50 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:50 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 14:13:50 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 14:13:50 INFO - Using env: {'DISPLAY': ':0', 14:13:50 INFO - 'HOME': '/home/cltbld', 14:13:50 INFO - 'LANG': 'en_US.UTF-8', 14:13:50 INFO - 'LANGUAGE': 'en_US:en', 14:13:50 INFO - 'LOGNAME': 'cltbld', 14:13:50 INFO - 'MAIL': '/var/mail/cltbld', 14:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:50 INFO - 'MOZ_NO_REMOTE': '1', 14:13:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:50 INFO - 'NO_EM_RESTART': '1', 14:13:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:50 INFO - 'PWD': '/builds/slave/test', 14:13:50 INFO - 'SHELL': '/bin/bash', 14:13:50 INFO - 'SHLVL': '1', 14:13:50 INFO - 'TERM': 'linux', 14:13:50 INFO - 'TMOUT': '86400', 14:13:50 INFO - 'USER': 'cltbld', 14:13:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:50 INFO - '_': '/tools/buildbot/bin/python'} 14:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:50 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)) 14:13:50 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)) 14:13:50 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)) 14:13:50 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)) 14:13:50 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)) 14:13:50 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)) 14:13:50 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)) 14:13:50 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)) 14:13:50 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)) 14:13:50 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)) 14:13:50 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)) 14:13:50 INFO - Cleaning up... 14:13:50 INFO - Return code: 0 14:13:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:13:50 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')]} 14:13:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:13:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:50 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')]} 14:13:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:13:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:13:50 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 0x21e11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x221ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247f310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, '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': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', '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 14:13:50 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 14:13:50 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 14:13:50 INFO - Using env: {'DISPLAY': ':0', 14:13:50 INFO - 'HOME': '/home/cltbld', 14:13:50 INFO - 'LANG': 'en_US.UTF-8', 14:13:50 INFO - 'LANGUAGE': 'en_US:en', 14:13:50 INFO - 'LOGNAME': 'cltbld', 14:13:50 INFO - 'MAIL': '/var/mail/cltbld', 14:13:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:13:50 INFO - 'MOZ_NO_REMOTE': '1', 14:13:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:13:50 INFO - 'NO_EM_RESTART': '1', 14:13:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:13:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:13:50 INFO - 'PWD': '/builds/slave/test', 14:13:50 INFO - 'SHELL': '/bin/bash', 14:13:50 INFO - 'SHLVL': '1', 14:13:50 INFO - 'TERM': 'linux', 14:13:50 INFO - 'TMOUT': '86400', 14:13:50 INFO - 'USER': 'cltbld', 14:13:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:13:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:13:50 INFO - '_': '/tools/buildbot/bin/python'} 14:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:13:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:13:51 INFO - Cleaning up... 14:13:51 INFO - Return code: 0 14:13:51 INFO - Running post-action listener: _resource_record_post_action 14:13:51 INFO - Running post-action listener: _start_resource_monitoring 14:13:51 INFO - Starting resource monitoring. 14:13:51 INFO - [mozharness: 2017-02-17 22:13:51.044406Z] Finished create-virtualenv step (success) 14:13:51 INFO - [mozharness: 2017-02-17 22:13:51.044917Z] Running install step. 14:13:51 INFO - Running pre-action listener: _resource_record_pre_action 14:13:51 INFO - Running main action method: install 14:13:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:13:51 INFO - Reading from file tmpfile_stdout 14:13:51 INFO - Detecting whether we're running mozinstall >=1.0... 14:13:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:13:51 INFO - Reading from file tmpfile_stdout 14:13:51 INFO - Output received: 14:13:51 INFO - Usage: mozinstall [options] installer 14:13:51 INFO - Options: 14:13:51 INFO - -h, --help show this help message and exit 14:13:51 INFO - -d DEST, --destination=DEST 14:13:51 INFO - Directory to install application into. [default: 14:13:51 INFO - "/builds/slave/test"] 14:13:51 INFO - --app=APP Application being installed. [default: firefox] 14:13:51 INFO - mkdir: /builds/slave/test/build/application 14:13:51 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'] 14:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:14:06 INFO - Reading from file tmpfile_stdout 14:14:06 INFO - Output received: 14:14:06 INFO - /builds/slave/test/build/application/firefox/firefox 14:14:06 INFO - Running post-action listener: _resource_record_post_action 14:14:06 INFO - [mozharness: 2017-02-17 22:14:06.953692Z] Finished install step (success) 14:14:06 INFO - [mozharness: 2017-02-17 22:14:06.953971Z] Running run-tests step. 14:14:06 INFO - Running pre-action listener: _resource_record_pre_action 14:14:06 INFO - Running main action method: run_tests 14:14:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:14:06 INFO - Python 2.7.3 14:14:06 INFO - Return code: 0 14:14:06 INFO - grabbing minidump binary from tooltool 14:14: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')]} 14:14:06 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 0x247c400>, '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 0x247d3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x247d840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:14:06 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 14:14:06 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 14:14:06 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 14:14:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:14:07 INFO - Return code: 0 14:14:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:14:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:14:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:14:07 INFO - ENV: RUST_BACKTRACE is now 1 14:14:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:14:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:14:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-050 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:14:07 INFO - Using env: {'DISPLAY': ':0', 14:14:07 INFO - 'HOME': '/home/cltbld', 14:14:07 INFO - 'LANG': 'en_US.UTF-8', 14:14:07 INFO - 'LANGUAGE': 'en_US:en', 14:14:07 INFO - 'LOGNAME': 'cltbld', 14:14:07 INFO - 'MAIL': '/var/mail/cltbld', 14:14:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:14:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:14:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:14:07 INFO - 'MOZ_NO_REMOTE': '1', 14:14:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:14:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:14:07 INFO - 'NO_EM_RESTART': '1', 14:14:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:14:07 INFO - 'PWD': '/builds/slave/test', 14:14:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:14:07 INFO - 'RUST_BACKTRACE': '1', 14:14:07 INFO - 'SHELL': '/bin/bash', 14:14:07 INFO - 'SHLVL': '1', 14:14:07 INFO - 'TERM': 'linux', 14:14:07 INFO - 'TMOUT': '86400', 14:14:07 INFO - 'USER': 'cltbld', 14:14:07 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393', 14:14:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:14:07 INFO - '_': '/tools/buildbot/bin/python'} 14:14:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:14:07 INFO - mozversion application_buildid: 20170217214152 14:14:07 INFO - mozversion application_changeset: 0930fdc4cf8e0116e19577005f57f7ec12336ced 14:14:07 INFO - mozversion application_display_name: Nightly 14:14:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:14:07 INFO - mozversion application_name: Firefox 14:14:07 INFO - mozversion application_remotingname: firefox 14:14:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:14:07 INFO - mozversion application_vendor: Mozilla 14:14:07 INFO - mozversion application_version: 54.0a1 14:14:07 INFO - mozversion platform_buildid: 20170217214152 14:14:07 INFO - mozversion platform_changeset: 0930fdc4cf8e0116e19577005f57f7ec12336ced 14:14:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:14:07 INFO - mozversion platform_version: 54.0a1 14:14:07 INFO - using testdate: 1487369647 14:14:07 INFO - actual date: 1487369647 14:14:07 INFO - starting webserver on 'localhost:48477' 14:14:07 INFO - SUITE-START | Running 2 tests 14:14:07 INFO - TEST-START | dromaeo_css 14:14:07 INFO - operating with platform_type : linux_ 14:14:07 INFO - Initialising browser for dromaeo_css test... 14:14:07 INFO - TEST-INFO | started process 15082 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHS4xqt/profile http://localhost:48477/getInfo.html) 14:14:10 INFO - PROCESS | 15082 | Iterating over profile /tmp/tmpHS4xqt/profile 14:14:11 INFO - PROCESS | 15082 | __metrics Screen width/height:1600/1200 14:14:11 INFO - PROCESS | 15082 | colorDepth:24 14:14:11 INFO - PROCESS | 15082 | Browser inner width/height: 1024/697 14:14:11 INFO - PROCESS | 15082 | __metrics 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/storage.sqlite-journal = Fri Feb 17 2017 14:14:11 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/bookmarkbackups = Fri Feb 17 2017 14:14:09 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/extensions.ini = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | Stat failure 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/key3.db = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/thumbnails = Fri Feb 17 2017 14:14:09 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/secmod.db = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/storage.sqlite = Fri Feb 17 2017 14:14:11 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/addons.json = Fri Feb 17 2017 14:14:09 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/cookies.sqlite = Fri Feb 17 2017 14:14:10 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/minidumps = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/extensions.json = Fri Feb 17 2017 14:14:09 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/pluginreg.dat = Fri Feb 17 2017 14:14:09 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/startupCache = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/cache2 = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/webappsstore.sqlite = Fri Feb 17 2017 14:14:11 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/frequencyCap.json = Fri Feb 17 2017 14:14:09 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/crashes = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:14:11 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/blocklist.xml = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/cert8.db = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/mimeTypes.rdf = Fri Feb 17 2017 14:14:11 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/user.js = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/content-prefs.sqlite = Fri Feb 17 2017 14:14:09 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/localstore.rdf = Fri Feb 17 2017 14:13:26 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/permissions.sqlite = Fri Feb 17 2017 14:14:08 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/gmp = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/compatibility.ini = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/places.sqlite-wal = Fri Feb 17 2017 14:14:11 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/places.sqlite-shm = Fri Feb 17 2017 14:14:08 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/.parentlock = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/directoryLinks.json = Fri Feb 17 2017 14:14:09 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/extensions = Fri Feb 17 2017 14:14:07 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/prefs.js = Fri Feb 17 2017 14:13:26 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/safebrowsing = Fri Feb 17 2017 14:14:11 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | No birth date. Using mtime. 14:14:11 INFO - PROCESS | 15082 | Using date: /tmp/tmpHS4xqt/profile/places.sqlite = Fri Feb 17 2017 14:14:11 GMT-0800 (PST) 14:14:11 INFO - PROCESS | 15082 | Stat failure 14:14:12 INFO - PROCESS | 15082 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:14:12 INFO - PROCESS | 15082 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:14:13 INFO - TEST-INFO | 15082: exit 0 14:14:13 INFO - Browser initialized. 14:14:13 INFO - Running cycle 1/1 for dromaeo_css test... 14:14:13 INFO - TEST-INFO | started process 15172 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHS4xqt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:14:25 INFO - PROCESS | 15172 | RSS: Main: 147173376 14:14:25 INFO - PROCESS | 15172 | 14:17:32 INFO - PROCESS | 15172 | Cycle 1(1): loaded http://localhost:48477/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48477/tests/dromaeo/cssquery-ext.html) 14:17:33 INFO - PROCESS | 15172 | RSS: Main: 281862144 14:17:33 INFO - PROCESS | 15172 | 14:19:14 INFO - PROCESS | 15172 | ************************* 14:19:14 INFO - PROCESS | 15172 | A coding exception was thrown and uncaught in a Task. 14:19:14 INFO - PROCESS | 15172 | 14:19:14 ERROR - PROCESS | 15172 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:19:14 INFO - PROCESS | 15172 | Full stack: 14:19:14 INFO - PROCESS | 15172 | ************************* 14:20:36 INFO - PROCESS | 15172 | Cycle 1(1): loaded http://localhost:48477/tests/dromaeo/cssquery-ext.html (next: http://localhost:48477/tests/dromaeo/cssquery-jquery.html) 14:20:37 INFO - PROCESS | 15172 | RSS: Main: 236707840 14:20:37 INFO - PROCESS | 15172 | 14:23:39 INFO - PROCESS | 15172 | Cycle 1(1): loaded http://localhost:48477/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48477/tests/dromaeo/cssquery-mootools.html) 14:23:40 INFO - PROCESS | 15172 | RSS: Main: 185352192 14:23:40 INFO - PROCESS | 15172 | 14:26:42 INFO - PROCESS | 15172 | Cycle 1(1): loaded http://localhost:48477/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48477/tests/dromaeo/cssquery-prototype.html) 14:26:42 INFO - PROCESS | 15172 | RSS: Main: 186347520 14:26:42 INFO - PROCESS | 15172 | 14:29:46 INFO - PROCESS | 15172 | Cycle 1(1): loaded http://localhost:48477/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48477/tests/dromaeo/cssquery-yui.html) 14:29:46 INFO - PROCESS | 15172 | RSS: Main: 186490880 14:29:46 INFO - PROCESS | 15172 | 14:32:49 INFO - PROCESS | 15172 | Cycle 1(1): loaded http://localhost:48477/tests/dromaeo/cssquery-yui.html (next: http://localhost:48477/tests/dromaeo/cssquery-dojo.html) 14:32:50 INFO - PROCESS | 15172 | RSS: Main: 280432640 14:32:50 INFO - PROCESS | 15172 | 14:32:50 INFO - PROCESS | 15172 | __start_tp_report 14:32:50 INFO - PROCESS | 15172 | _x_x_mozilla_page_load 14:32:50 INFO - PROCESS | 15172 | _x_x_mozilla_page_load_details 14:32:50 INFO - PROCESS | 15172 | |i|pagename|runs| 14:32:50 INFO - PROCESS | 15172 | |0;dojo.html;2560.705518865331;2569.2806014315993;2590.8963641454343;2594.3254753764027;2594.81836271461;4655.650826188041;4773.260144677572;4790.377250957375;4803.471618122004;4814.229723244282;4761.666683332168;4790.137775200463;4802.08760335536;4804.663673542854;4857.514248575149;4428.645708343325;4440.378347031418;4456.732596044248;4492.3710680504755;4575.359449677031;4487.371767952489;4522.796474158671;4527.32090186473;4547.909041819162;4555.407796986387;4321.200577893069;4389.978050109761;4393.07745373472;4491.842785502525;4505.189065968125;4417.138657961698;4422.933655995163;4429.069895321986;4433.13553856998;4451.287793952984;4435.268180750205;4449.310356894686;4478.104379124188;4485.70842895214;4501.414816073922;4819.831305904277;4839.975800121012;4874.46380898101;4883.974365383274;4940.3330550375595;5103.209002604565;5144.948550514484;5150.922736158961;5211.7394130293715;5219.321488206509;5182.507661772163;5196.480351964811;5197;5200.271961925334;5209.374875014984;4792.976035119802;4825.227963525819;4860.36815214027;4863.124637565237;4872.926906096412;5329.413764485904;5371.838844834661;5373.408925018245;5378.865528361822;5383.623146379794;4887.560119589183;4927.384076990376;4939.580135688435;5053.9241911371355;5093.23601459784;5189.325387699575;5221.373435187802;5249.265102885599;5253.23828044939;5261.894762104736;5174.741262936838;5177.119889618773;5179.533841954242;5230.0585894539345;5283.815066472655;4923.606111511071;4962.975185124052;4964.578010869117;5008.5993120550265;5018.5232402921665;4337.479502459725;4352.477702675699;4357;4360.084382279693;4364.8254069837485;3760.1163726524246;3772.4529943158086;3774.867879624255;3784.5836957934607;3802.391617341212;4461.397711308931;4465;4470.195364834296;4483.865484035484;4506.909861802746;5201.609879258995;5211.530962213419;5218.425973142951;5219.373675159005;5253.947460525345;4409.536998615164;4455.287154055336;4457.130859482435;4471.8434854779925;4474.328850672425;4803.567678908915;4805.6155507559315;4809.254565542276;4812.06164797861;4815.374001379868;4833.613310935187;4920.901581968341;4926.926096108489;4930.6795058321095;4987.251912213197;5281.943611277683;5298.7350632469;5300.575953923754;5301;5303.708296043755;4679.251319788817;4706.788194531323;4824.131656301899;4836.709797412166;4853.247746599282;5131.230315452712;5132.486751324987;5134.178531434953;5136.434992150786;5189.299444574934;5175.9741201293755;5188.714620695898;5188.818391356284;5195.246689229964;5239.345081864767;4922.827701030446;4973.328600638867;4975.4278258000795;5034.84895453137;5042.09242336383;4966.751662416937;5001.299818025406;5035.92446113316;5059.519345662156;5121.231815227746;4994.825181118644;4996.675216110941;4997.9750101249265;5022.824201152942;5036.370453693288;5127.743612819419;5146.4338922717725;5152.252923326172;5152.458991805806;5203.19350500673;4780.476157312963;4891.779869905791;5002;5012.04771093491;5014.87462813418 14:32:50 INFO - PROCESS | 15172 | |1;ext.html;557.4258513730857;573.5239751006665;574.0528128587832;582.5456144207515;606.4572207873953;26851;27318.18045458637;36139.277214455695;38181.42727859085;38390.424143637865;38803.22393552127;39157.41263881044;39169.804150979275;39198.60801391985;39217.21565568694;7582.9241707582905;7778.299953004229;7779.533228006309;7789.260020298063;7792.844143117134;6778.728850845961;6941.930580694204;9378.109079637423;9617.519124043805;9630.422174669542;9639.373440726331;9676.806463870718;9690.75773105671;9704.126628603424;9715.70852874415;9746.853797193047;9795.216382689368;9804.754881128029;9810.264230182693;9814.852777208347;9567.904320956772;9623.230141588727;9628.71113866585;9629.470379129145;9640.75898102546;35603.643963560295;35689.82155089234;35780.64219357825;35805;35833.641663583294;3210.085125739153;3226.725728313096;3229.1281354034513;3247.52910827931;3263.379957808009;26740.46519069608;26765.86617066902;26929.73070269292;27265.86367068172;28132.578011329846;3178.952315715266;3199.168216263765;3218.535252286495;3284.2117891706057;3433.055909624873;209666;209914;209942;210066.94966525224;210285;38231.4265286021;44998.32502512465;45479.317810232875;45514.31728524075;45567.54432455633;38194.23611527754;41162.1767564647;41174;41197;46935.20490287185;11846.407679616157;12489.063320251018;12558.560450384375;12575.496980120693;12575.685607859878;9253.67612133572;9350;9360.4383736976;9361.251099911991;9364.438133712;12922.353882305848;13361.599152025456;15016.47442339513;15040.32318545668;15246.46637367709;6146.170267013986;6387.361263873576;6496.7726129585235;6507.468651646063;6531.967340163269;739.3900032473231;755.6335177438938;764.8699721047435;772.5441989226271;775.1744392222378;25926.74073259281;26486;27318.180454586392;27393.86303068472;27394.726052739617;914.2731528434911;924.3945215883607;926.6200857648332;928.7167413938679;929.3819609959437;14161.29193540319;14162.291885405688;14393.136411815325;14474.638134046732;14505.782413263809;315443;319226;319612;320571;321578;47514.524854751704;47562.146271223835;47874.5212547877;48002;48103.75948120307;1156.2831044752306;1200.327816422806;1200.8558972923306;1203.602811072344;1204.7108693913397;25137.207465685697;25516.74483255218;25728.742712572635;26277.868610656824;26305.73694263033;40329.79835100923;40818.18363632651;40842.38736419015;40873.59126408698;40909.79545102255;218148;219174.61650767215;219647;219968;221070;21317.46706332354;21873.234436794635;23950;24459.38851528726;24791.132310369052;38417;39116.804415977735;39239.21521569495;39294.41058384184;39333.2133357337;32021;33071.66928330686;33296;33321.666783332825;34703.47944780881;32755.836220819696;32784.67215327911;33262.33475330528;33467.16332091717;34041.659583403845;32549.837250813594;32902.67097328996;33112.668873310955;33227.16932076718;33232.50151247685;27760;29331.413371732873;29707.257318567208;29871.551926720684;29878.70121298759;22660.660090098332;22673.659895101257;22729.88635056814;22955;22967.425814354774 14:32:50 INFO - PROCESS | 15172 | |2;jquery.html;347.7166109620657;375.8815972968516;381.2223064947506;396.32426712455265;419.2223425545613;12278.938605306972;12572.30852303123;12775.50233939221;17924;17961.550961225978;17305.39431119911;17427.390041348524;17729.55676108098;17827.197776100074;17871.463856084338;5043.974780126095;5317.255584218213;5322.361316642008;5327.30745003151;5328.280682107924;5223.582113430916;5291.206319052154;5326.760295786679;5331.520163185312;5494.972525137368;5591.133374326986;5594.188842617818;5600.1599760035915;5603.943960560403;5610.775568977236;5399.217113518518;5543.417941116206;5576.525995290405;5599.244102046233;5612.607117501756;5456.154296084113;5461.344638643349;5462.890742185174;5468.863278418072;5472.206530053159;12990.935045324713;15319.234038298151;21278;21494.140234390605;21508.784912150833;2070.3064473401428;2110.8100270975688;2114.3762590035976;2124;2151.8924053797405;9313.53432328381;9351.485668288242;9357.906420935771;9840.65557705477;9928.205743540499;1636.5663099278581;1680.1935071165908;1696.8388003139682;1700.9744853827203;1710.7262837945868;70963.29036709618;73335;73669.63165184193;75294.62352688202;75451.62274188649;10795.244332896778;10796.136309095253;10936.367770598148;10942;10991.890081099247;15233.542993710207;15272.31274592646;15281.38874445032;15351.15568643713;15385.153816539974;6724.462043036546;6855.794326170223;7014.088168538057;7248;7306.561606303639;6367.745290188341;6369.9681501592195;6544.181977252843;6554.573952693059;6586.901196482057;4086.7956602169775;4278.251306021434;4309.806058727365;4619.445666520039;4655.930161047587;1816.1464111867401;1826.214727667116;1856.4430670798977;1916.3292847503542;1967.950801229952;785.2696991797683;789.163486704084;796.9442139050211;799.4683535448861;809.7246936041774;17045.659086818196;17715.73426398605;17771.289148433916;17924.820751792577;18164.18261178224;791.1297572670132;792.2038351456749;795.2167115391335;800.795797071743;804.6057431858347;9340.906590934139;9363.25093992479;9618.855717164248;9650.179734722487;9897.37719504332;470106;473489;496387;502065;504420;83767.5811620938;86109.56945215234;86337.13662863417;86729.5663521691;86808.5659571698;914.2411798207472;922.9861552076724;952.1620973543238;961.7162936933621;972.7081875437341;15805.288762005508;15844.76232856485;16577.50267491977;16580.502584922473;16843.326266949185;36999.26001480009;37460.625393745715;37926.62073379231;38436;38813;47673.52326476691;49750.75124624354;50076;50573.49426505688;53437.73281133569;7639.121501027453;7759.262870027338;7821.687132514636;7914.762557123296;8260.83478330442;8803.823923521622;11585.942070289595;11691.766164676828;12330.876691232972;12432.564860229848;9976.05227503386;10095.949520252352;10377.325473844176;10497.265191436527;10798.136149107895;10210.744731381776;10349.74125646865;10406.271560990961;10435.94782026085;10646;10097.293189476666;10666.306690065432;10689.94655026751;10693.876330880386;10998.835017474585;7536.434767392358;7590.734324298623;7774.067111946602;7823.765287041398;7977.960110199412;8339.54132522717;9966;10070.04334588213;10289.434081125311;10328.638497652546 14:32:50 INFO - PROCESS | 15172 | |3;mootools.html;1193.8089905615104;1302.2446980751165;1331.4607583928512;1337.9866201337986;1343.9731205375892;6165.907511387327;7245;7478.046171762157;7479.878018297244;7718.807029824243;7544.622768861563;7623.504472209303;7623.733169339061;7625.656845441955;7628.847423051536;2009.537806304547;2045.0694933805082;2048.1909645689943;2048.4878780304925;2049.3646969439446;1762.3743571032292;1811.0401487211734;1991.163711241282;2040.8877511736848;2044.4071219346372;1998.1208268361947;2000.4398768344886;2001.5996800639857;2003.7795842457317;2009.377093101136;1953.082051435823;1968.9310874119408;1973.4079776067122;1978.1889425335687;1980.1584326661255;1969.094216660338;2023.6053969475959;2041.7141600175987;2044.63196624609;2046;7391.630418479054;7462;7464.2535746425465;7468.439867009996;7477.7008919643695;4219.324908014733;4321.135772845443;4330.112326972994;4331.826726930919;4336.154449882274;2608.2044976282227;2703.4863375958703;2748.8833598129327;2750.037486879596;2755.3111722069484;1040.9011143941314;1063.7074804428767;1064.6060957445718;1066.1151244467073;1082.7888561730465;9212.677556285566;9499.43003419797;9566.378185417925;9683.273754468442;9726.17327527161;3639.890803275906;3693.778373297611;3773.3019391412677;3777.792221427848;3820.789856557916;7749.496282741604;7758;7762.728304509315;7770.067591889009;7779.45543811939;4377.824887004548;4378.211921854033;4380.080183161572;4385.407969924019;4386.758728269976;3878.127421330178;3990.1221731219084;4069.6337329640473;4072.3077076897;4077.673786097105;3210.6307774605752;3233.0785726067957;3303.50447432887;3307.8676852926096;3309.933801324008;981.5779214937645;984.2814745236018;986.4032260482448;997.436448406648;1001.4492029383955;686;703.8134894253027;716.8781307177792;723.3453724379445;740.167311774254;4649.837255696035;4692.765361731968;4805.13507568634;4892.877678058077;4903.583195428429;512.2648998686897;518.7121147763028;527.6649327676953;530.7823792245161;539.6842846934587;3214.1803840020643;3284.080457471912;3595.7842529448317;3743.082944678571;3756.6806821419937;8685.6525738971;8866.512341821262;8867.91132088671;8871.512066836256;9028.638854445748;8724.825503489894;8798.428102173339;8808.20726134651;8825.823483530294;8853.60158792843;6783.355581219875;6937.537174303151;6943.791686249521;6947.617881016491;6950.860982780316;5040.269160971712;5110.565601923878;5125.666831655931;5148.172398798372;5227.660202086852;7877.803054923673;7904.723334683258;7947.364210863001;8027.317677997202;8077.192280771876;7954.204579542;8198.590070496331;8265.380096492907;8269.09040005611;8269.751907442786;6380.553361264852;6530.216374035146;6585.176852893388;6587.143671322698;6593.670316484061;7044.260352662896;7094.787156385317;7103.396211322096;7107.431405487445;7115.288471152843;6240.875182496416;6332.778352757631;6383.329750376143;6384.712687929147;6550.148480697479;6335.366463353628;6371.426571608577;6385.393387628168;6386.3395517164945;6390.840228994312;5433.4566543345345;5460.153676180198;5512.944870551403;5523.917141242965;5525.171224316385;4462.352958820888;4464.17412778637;4487.259602165736;4490.57339552742;4521.97739011316;4454.7549884756645;4474.955250447454;4495.348174514743;4574.954250457586;4706.247000479907 14:32:50 INFO - PROCESS | 15172 | |4;prototype.html;289.33741731435015;324.71262932304916;347.8747650845696;349.50934265358245;355.60527814126317;4711.552402521764;4841.201201801703;4884.853454396365;4902.852914412573;4913.852584422473;4684.906301873961;4826.179549476588;4879.487653796354;4886.071646387193;4911.361523002014;3856.1709232515072;3932.488776459056;3946.171304026158;3959.841606335743;3971.2256110058406;3706.610805865372;3779.8677046303383;3806.3338915689783;3975.807257822656;3981.2236613860164;3862.0151861275476;3871.477350557667;3894.5727759571537;3908.237893610747;3915.158240978189;3854.2869569129803;3864.555576108727;3879.35990561557;3889.7860617666015;3928.292907276689;3291.9835400822844;3379.746519011084;3511.1924257420937;3520.102373894666;3535.699465545432;4511.639068874482;4791.832285869978;4795.105933156418;4810.6151507879285;4819.638527110481;4492.7528985905765;4509.503954564995;4510.849733318016;4519.254323036695;4526.2984237442915;4913.31213630092;5174.767135478913;5195.298634684307;5201.347312870425;5212.556932660728;3892.6885849132286;3895.668868146211;3896.7204185551204;3913.4716813230134;3928.9214215715524;5369.778133120136;5392.78428862845;5403.243545903577;5410.296661434028;5429.728513574384;3997.720159588801;4014.6788256939376;4018.598140185958;4035.1929614077303;4037.313656678372;3911.941320880189;3965.78188199646;3990.022444501116;3998.9400158997637;3999.1201935574127;4144.48193975753;4216.620504154641;4253.723507971972;4356.6732495063;4432.58690808076;5081.7967281309075;5128.153854613947;5147.948520514822;5150.510701483353;5171.5862730981435;1355.9254241016838;1358.3208395802098;1366.4670778396433;1375.0580852116332;1377.4008306386554;940.9012053734259;970.1317320997667;986.284943416032;993.4188499727596;1005.772956992468;4357.455318085239;4359.689364636748;4392.3191905254735;4414.977925110354;4427.512973572904;5105.948940510547;5132.3327967364;5138.715321169708;5161.561267292323;5168.3539557555305;4248.851290204828;4456.5043575597765;4470.508244093147;4473;4573.428321459817;2715.416185520083;2718.4427192425505;2719.551274039801;2735.193118030186;2743.3690251242324;4854;4867.026594681078;4878.292647566083;4888.7311197884455;4892.437369702529;4994.675346102492;5216.209328009572;5272.260154149121;5276.762545685453;5299.682019078868;4850.83022094225;4855.660103792771;4866.829660961991;4870.245112007645;4879.170541007966;5123.231515272739;5139.563137133386;5151;5182.455842136672;5186.351706036746;4893.241547560134;5040.672356296829;5045.974770126126;5052.311812377059;5092.923606145837;4815.374001379938;4833.758312084311;4835.443923948791;4842.757862106951;4855.854324370274;3610.4403817408343;3618.2401695644207;3626.546681664792;3638.072291565634;3650.8174591270863;4960.553550180501;5070;5095.464976177596;5129.8717532062;5166.793328266827;4947.752612369295;4949.455559888482;4964.180910149929;4993.076280888047;5009.77456014473;4997.75056235941;5001.249063202598;5019.774110164978;5023.8492845214705;5033.974830125826;4731.683067968496;4862.489438609036;4870.659053866337;4939.5554400104165;4977.477364876636;4997.5002499749735;4997.775100120431;5000.849804544902;5009.624278179078;5013.749312534285;4816.95183048165;4916.050369778104;4923.986950635221;4928.926066108939;4940.851774446773 14:32:50 INFO - PROCESS | 15172 | |5;yui.html;654.1822721598003;660.5112216959451;673.1350214973759;688.7933619914027;706.8727629026777;12454.564090256843;13476.056676032682;13494.122882012662;13520.729585408286;13586.456541738331;9481.52592370382;12306.56927007555;12404.069694772883;12437.191582547128;12437.626871193856;599.9010163323057;790.7746292306693;810.027966440271;817.1052697296481;818.6807145213371;816.2694388522269;817.0440584516131;818.6193420059672;818.97133600324;819.2012787532168;858.0304256190497;858.0518527027638;858.1761508951414;858.4720396955859;858.7981824271292;849.3714651158099;854.2824027816602;855.7218903856209;858.7938894665298;859.2138193552892;626.3830127324582;630;631.2929518938772;631.4979591224987;631.561065059779;13501.797473037814;13560.864391356059;13575.52485662997;13609.591712248648;13634;11109.555617775279;12073.275603463733;12159.209651372632;13031.86968130316;13044;10120.240981926312;10203.33678310915;10307.433091179983;10332.690019299358;10702.625408110678;3142.889998850052;3158.3209609933974;3289.8190599517015;3322.401967645823;3328.0681409205463;63101;63837;64270.357296426904;64979;65943;9651.469169195761;9674.274429417821;9783.26625503076;9787.510624468749;10151.543180556893;17626.64746705059;17783.19975601101;17818.732719009226;17892.552686182687;18255.452336429928;5933;5988.3113441953865;6060.2424696912885;6098.847528811815;6121.693915304217;3475.1138459692615;3505.94741078884;3526.2947410517486;3560.323538527723;3589.335972845032;2819.873105710248;2890.783191260664;2903.6951120132503;2907.592936988823;2920.240737408247;473.40587562608704;479.4199019186823;482.78998635593626;489.64500736966124;493.2305603258928;598.6976576828673;623.7692053940071;623.9968800155971;639.7888696730068;653.9542232043807;7932;8100.432969692183;8377;8586.356023298278;8745.737627871174;588.4791959116127;614.4961131871822;621.4251817069193;623.4669357699192;624.6064979063252;3777;3781.149241420713;3995.380715989026;4453.599176074169;4707.458642256116;49378.75310623424;61319;61576.69211653913;63632.681836591444;63660.68169659122;48416.27375589369;51040;51301.74349128231;52682.20976685353;53347.19979200315;46889.765551172706;47451.525484744714;47822.76088619535;48209;49693.75153124211;8552.315814734931;8885.42244754089;8921.955390223007;9079.636814527345;9175.954120229357;33338;33905.66094339025;34142.487862681584;34241;35446.822765886005;39758;40108;42281.0403271768;43312.350314745934;44324.33513497236;10803.729906752395;11049.668509944715;11148.832767508657;11258.66224013281;11317.490712918103;12253.203541769757;12270.386480675752;12282.75434491323;12447.751044979232;13116.606501804961;10790.67627971162;10861.511231994737;10923;11131.888681113085;11415.714607134889;11120.388378639253;11327.886721133013;11328.433578320886;11591.072714182677;11746.823797643214;10700.464976750976;10722.142228621535;10885.782284354427;10950.507227174638;11049.779004420026;8876.822463550563;9003.54982250898;9050;9251.629934802533;9536.380135291183;8780.209781119474;9587.520623968912;9624;9664.602605814198;9848.753781155528 14:32:50 INFO - PROCESS | 15172 | __end_tp_report 14:32:50 INFO - PROCESS | 15172 | __start_cc_report 14:32:50 INFO - PROCESS | 15172 | _x_x_mozilla_cycle_collect,1642 14:32:50 INFO - PROCESS | 15172 | __end_cc_report 14:32:50 INFO - PROCESS | 15172 | __startTimestamp1487370770169__endTimestamp 14:32:50 INFO - PROCESS | 15172 | 14:32:50 INFO - PROCESS | 15172 | ------- Summary: start ------- 14:32:50 INFO - PROCESS | 15172 | Number of tests: 6 14:32:50 INFO - PROCESS | 15172 | 14:32:50 INFO - PROCESS | 15172 | [#0] dojo.html Cycles:165 Average:4784.63 Median:4891.78 stddev:524.89 (10.7%) stddev-sans-first:496.66 14:32:50 INFO - PROCESS | 15172 | Values: 2560.7 2569.3 2590.9 2594.3 2594.8 4655.7 4773.3 4790.4 4803.5 4814.2 4761.7 4790.1 4802.1 4804.7 4857.5 4428.6 4440.4 4456.7 4492.4 4575.4 4487.4 4522.8 4527.3 4547.9 4555.4 4321.2 4390.0 4393.1 4491.8 4505.2 4417.1 4422.9 4429.1 4433.1 4451.3 4435.3 4449.3 4478.1 4485.7 4501.4 4819.8 4840.0 4874.5 4884.0 4940.3 5103.2 5144.9 5150.9 5211.7 5219.3 5182.5 5196.5 5197.0 5200.3 5209.4 4793.0 4825.2 4860.4 4863.1 4872.9 5329.4 5371.8 5373.4 5378.9 5383.6 4887.6 4927.4 4939.6 5053.9 5093.2 5189.3 5221.4 5249.3 5253.2 5261.9 5174.7 5177.1 5179.5 5230.1 5283.8 4923.6 4963.0 4964.6 5008.6 5018.5 4337.5 4352.5 4357.0 4360.1 4364.8 3760.1 3772.5 3774.9 3784.6 3802.4 4461.4 4465.0 4470.2 4483.9 4506.9 5201.6 5211.5 5218.4 5219.4 5253.9 4409.5 4455.3 4457.1 4471.8 4474.3 4803.6 4805.6 4809.3 4812.1 4815.4 4833.6 4920.9 4926.9 4930.7 4987.3 5281.9 5298.7 5300.6 5301.0 5303.7 4679.3 4706.8 4824.1 4836.7 4853.2 5131.2 5132.5 5134.2 5136.4 5189.3 5176.0 5188.7 5188.8 5195.2 5239.3 4922.8 4973.3 4975.4 5034.8 5042.1 4966.8 5001.3 5035.9 5059.5 5121.2 4994.8 4996.7 4998.0 5022.8 5036.4 5127.7 5146.4 5152.3 5152.5 5203.2 4780.5 4891.8 5002.0 5012.0 5014.9 14:32:50 INFO - PROCESS | 15172 | 14:32:50 INFO - PROCESS | 15172 | [#1] ext.html Cycles:180 Average:39925.35 Median:19068.27 stddev:66534.40 (348.9%) stddev-sans-first:66655.39 14:32:50 INFO - PROCESS | 15172 | Values: 557.4 573.5 574.1 582.5 606.5 26851.0 27318.2 36139.3 38181.4 38390.4 38803.2 39157.4 39169.8 39198.6 39217.2 7582.9 7778.3 7779.5 7789.3 7792.8 6778.7 6941.9 9378.1 9617.5 9630.4 9639.4 9676.8 9690.8 9704.1 9715.7 9746.9 9795.2 9804.8 9810.3 9814.9 9567.9 9623.2 9628.7 9629.5 9640.8 35603.6 35689.8 35780.6 35805.0 35833.6 3210.1 3226.7 3229.1 3247.5 3263.4 26740.5 26765.9 26929.7 27265.9 28132.6 3179.0 3199.2 3218.5 3284.2 3433.1 209666.0 209914.0 209942.0 210066.9 210285.0 38231.4 44998.3 45479.3 45514.3 45567.5 38194.2 41162.2 41174.0 41197.0 46935.2 11846.4 12489.1 12558.6 12575.5 12575.7 9253.7 9350.0 9360.4 9361.3 9364.4 12922.4 13361.6 15016.5 15040.3 15246.5 6146.2 6387.4 6496.8 6507.5 6532.0 739.4 755.6 764.9 772.5 775.2 25926.7 26486.0 27318.2 27393.9 27394.7 914.3 924.4 926.6 928.7 929.4 14161.3 14162.3 14393.1 14474.6 14505.8 315443.0 319226.0 319612.0 320571.0 321578.0 47514.5 47562.1 47874.5 48002.0 48103.8 1156.3 1200.3 1200.9 1203.6 1204.7 25137.2 25516.7 25728.7 26277.9 26305.7 40329.8 40818.2 40842.4 40873.6 40909.8 218148.0 219174.6 219647.0 219968.0 221070.0 21317.5 21873.2 23950.0 24459.4 24791.1 38417.0 39116.8 39239.2 39294.4 39333.2 32021.0 33071.7 33296.0 33321.7 34703.5 32755.8 32784.7 33262.3 33467.2 34041.7 32549.8 32902.7 33112.7 33227.2 33232.5 27760.0 29331.4 29707.3 29871.6 29878.7 22660.7 22673.7 22729.9 22955.0 22967.4 14:32:50 INFO - PROCESS | 15172 | 14:32:50 INFO - PROCESS | 15172 | [#2] jquery.html Cycles:180 Average:27481.74 Median:49913.38 stddev:80534.39 (161.3%) stddev-sans-first:80734.54 14:32:50 INFO - PROCESS | 15172 | Values: 347.7 375.9 381.2 396.3 419.2 12278.9 12572.3 12775.5 17924.0 17961.6 17305.4 17427.4 17729.6 17827.2 17871.5 5044.0 5317.3 5322.4 5327.3 5328.3 5223.6 5291.2 5326.8 5331.5 5495.0 5591.1 5594.2 5600.2 5603.9 5610.8 5399.2 5543.4 5576.5 5599.2 5612.6 5456.2 5461.3 5462.9 5468.9 5472.2 12990.9 15319.2 21278.0 21494.1 21508.8 2070.3 2110.8 2114.4 2124.0 2151.9 9313.5 9351.5 9357.9 9840.7 9928.2 1636.6 1680.2 1696.8 1701.0 1710.7 70963.3 73335.0 73669.6 75294.6 75451.6 10795.2 10796.1 10936.4 10942.0 10991.9 15233.5 15272.3 15281.4 15351.2 15385.2 6724.5 6855.8 7014.1 7248.0 7306.6 6367.7 6370.0 6544.2 6554.6 6586.9 4086.8 4278.3 4309.8 4619.4 4655.9 1816.1 1826.2 1856.4 1916.3 1968.0 785.3 789.2 796.9 799.5 809.7 17045.7 17715.7 17771.3 17924.8 18164.2 791.1 792.2 795.2 800.8 804.6 9340.9 9363.3 9618.9 9650.2 9897.4 470106.0 473489.0 496387.0 502065.0 504420.0 83767.6 86109.6 86337.1 86729.6 86808.6 914.2 923.0 952.2 961.7 972.7 15805.3 15844.8 16577.5 16580.5 16843.3 36999.3 37460.6 37926.6 38436.0 38813.0 47673.5 49750.8 50076.0 50573.5 53437.7 7639.1 7759.3 7821.7 7914.8 8260.8 8803.8 11585.9 11691.8 12330.9 12432.6 9976.1 10095.9 10377.3 10497.3 10798.1 10210.7 10349.7 10406.3 10435.9 10646.0 10097.3 10666.3 10689.9 10693.9 10998.8 7536.4 7590.7 7774.1 7823.8 7978.0 8339.5 9966.0 10070.0 10289.4 10328.6 14:32:50 INFO - PROCESS | 15172 | 14:32:50 INFO - PROCESS | 15172 | [#3] mootools.html Cycles:180 Average:4768.14 Median:2833.44 stddev:2645.37 (93.4%) stddev-sans-first:2639.15 14:32:50 INFO - PROCESS | 15172 | Values: 1193.8 1302.2 1331.5 1338.0 1344.0 6165.9 7245.0 7478.0 7479.9 7718.8 7544.6 7623.5 7623.7 7625.7 7628.8 2009.5 2045.1 2048.2 2048.5 2049.4 1762.4 1811.0 1991.2 2040.9 2044.4 1998.1 2000.4 2001.6 2003.8 2009.4 1953.1 1968.9 1973.4 1978.2 1980.2 1969.1 2023.6 2041.7 2044.6 2046.0 7391.6 7462.0 7464.3 7468.4 7477.7 4219.3 4321.1 4330.1 4331.8 4336.2 2608.2 2703.5 2748.9 2750.0 2755.3 1040.9 1063.7 1064.6 1066.1 1082.8 9212.7 9499.4 9566.4 9683.3 9726.2 3639.9 3693.8 3773.3 3777.8 3820.8 7749.5 7758.0 7762.7 7770.1 7779.5 4377.8 4378.2 4380.1 4385.4 4386.8 3878.1 3990.1 4069.6 4072.3 4077.7 3210.6 3233.1 3303.5 3307.9 3309.9 981.6 984.3 986.4 997.4 1001.4 686.0 703.8 716.9 723.3 740.2 4649.8 4692.8 4805.1 4892.9 4903.6 512.3 518.7 527.7 530.8 539.7 3214.2 3284.1 3595.8 3743.1 3756.7 8685.7 8866.5 8867.9 8871.5 9028.6 8724.8 8798.4 8808.2 8825.8 8853.6 6783.4 6937.5 6943.8 6947.6 6950.9 5040.3 5110.6 5125.7 5148.2 5227.7 7877.8 7904.7 7947.4 8027.3 8077.2 7954.2 8198.6 8265.4 8269.1 8269.8 6380.6 6530.2 6585.2 6587.1 6593.7 7044.3 7094.8 7103.4 7107.4 7115.3 6240.9 6332.8 6383.3 6384.7 6550.1 6335.4 6371.4 6385.4 6386.3 6390.8 5433.5 5460.2 5512.9 5523.9 5525.2 4462.4 4464.2 4487.3 4490.6 4522.0 4454.8 4475.0 4495.3 4575.0 4706.2 14:32:50 INFO - PROCESS | 15172 | 14:32:50 INFO - PROCESS | 15172 | [#4] prototype.html Cycles:180 Average:4240.20 Median:4822.91 stddev:1182.10 (24.5%) stddev-sans-first:1147.62 14:32:50 INFO - PROCESS | 15172 | Values: 289.3 324.7 347.9 349.5 355.6 4711.6 4841.2 4884.9 4902.9 4913.9 4684.9 4826.2 4879.5 4886.1 4911.4 3856.2 3932.5 3946.2 3959.8 3971.2 3706.6 3779.9 3806.3 3975.8 3981.2 3862.0 3871.5 3894.6 3908.2 3915.2 3854.3 3864.6 3879.4 3889.8 3928.3 3292.0 3379.7 3511.2 3520.1 3535.7 4511.6 4791.8 4795.1 4810.6 4819.6 4492.8 4509.5 4510.8 4519.3 4526.3 4913.3 5174.8 5195.3 5201.3 5212.6 3892.7 3895.7 3896.7 3913.5 3928.9 5369.8 5392.8 5403.2 5410.3 5429.7 3997.7 4014.7 4018.6 4035.2 4037.3 3911.9 3965.8 3990.0 3998.9 3999.1 4144.5 4216.6 4253.7 4356.7 4432.6 5081.8 5128.2 5147.9 5150.5 5171.6 1355.9 1358.3 1366.5 1375.1 1377.4 940.9 970.1 986.3 993.4 1005.8 4357.5 4359.7 4392.3 4415.0 4427.5 5105.9 5132.3 5138.7 5161.6 5168.4 4248.9 4456.5 4470.5 4473.0 4573.4 2715.4 2718.4 2719.6 2735.2 2743.4 4854.0 4867.0 4878.3 4888.7 4892.4 4994.7 5216.2 5272.3 5276.8 5299.7 4850.8 4855.7 4866.8 4870.2 4879.2 5123.2 5139.6 5151.0 5182.5 5186.4 4893.2 5040.7 5046.0 5052.3 5092.9 4815.4 4833.8 4835.4 4842.8 4855.9 3610.4 3618.2 3626.5 3638.1 3650.8 4960.6 5070.0 5095.5 5129.9 5166.8 4947.8 4949.5 4964.2 4993.1 5009.8 4997.8 5001.2 5019.8 5023.8 5034.0 4731.7 4862.5 4870.7 4939.6 4977.5 4997.5 4997.8 5000.8 5009.6 5013.7 4817.0 4916.1 4924.0 4928.9 4940.9 14:32:50 INFO - PROCESS | 15172 | 14:32:50 INFO - PROCESS | 15172 | [#5] yui.html Cycles:180 Average:14107.74 Median:24345.89 stddev:17308.86 (71.1%) stddev-sans-first:17327.93 14:32:50 INFO - PROCESS | 15172 | Values: 654.2 660.5 673.1 688.8 706.9 12454.6 13476.1 13494.1 13520.7 13586.5 9481.5 12306.6 12404.1 12437.2 12437.6 599.9 790.8 810.0 817.1 818.7 816.3 817.0 818.6 819.0 819.2 858.0 858.1 858.2 858.5 858.8 849.4 854.3 855.7 858.8 859.2 626.4 630.0 631.3 631.5 631.6 13501.8 13560.9 13575.5 13609.6 13634.0 11109.6 12073.3 12159.2 13031.9 13044.0 10120.2 10203.3 10307.4 10332.7 10702.6 3142.9 3158.3 3289.8 3322.4 3328.1 63101.0 63837.0 64270.4 64979.0 65943.0 9651.5 9674.3 9783.3 9787.5 10151.5 17626.6 17783.2 17818.7 17892.6 18255.5 5933.0 5988.3 6060.2 6098.8 6121.7 3475.1 3505.9 3526.3 3560.3 3589.3 2819.9 2890.8 2903.7 2907.6 2920.2 473.4 479.4 482.8 489.6 493.2 598.7 623.8 624.0 639.8 654.0 7932.0 8100.4 8377.0 8586.4 8745.7 588.5 614.5 621.4 623.5 624.6 3777.0 3781.1 3995.4 4453.6 4707.5 49378.8 61319.0 61576.7 63632.7 63660.7 48416.3 51040.0 51301.7 52682.2 53347.2 46889.8 47451.5 47822.8 48209.0 49693.8 8552.3 8885.4 8922.0 9079.6 9176.0 33338.0 33905.7 34142.5 34241.0 35446.8 39758.0 40108.0 42281.0 43312.4 44324.3 10803.7 11049.7 11148.8 11258.7 11317.5 12253.2 12270.4 12282.8 12447.8 13116.6 10790.7 10861.5 10923.0 11131.9 11415.7 11120.4 11327.9 11328.4 11591.1 11746.8 10700.5 10722.1 10885.8 10950.5 11049.8 8876.8 9003.5 9050.0 9251.6 9536.4 8780.2 9587.5 9624.0 9664.6 9848.8 14:32:50 INFO - PROCESS | 15172 | -------- Summary: end -------- 14:32:50 INFO - PROCESS | 15172 | 14:32:51 INFO - TEST-INFO | 15172: exit 0 14:32:51 INFO - TEST-OK | dromaeo_css | took 1123857ms 14:32:51 INFO - TEST-START | kraken 14:32:51 INFO - operating with platform_type : linux_ 14:32:51 INFO - Initialising browser for kraken test... 14:32:51 INFO - TEST-INFO | started process 15488 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCMLbCa/profile http://localhost:48477/getInfo.html) 14:32:54 INFO - PROCESS | 15488 | Iterating over profile /tmp/tmpCMLbCa/profile 14:32:55 INFO - PROCESS | 15488 | __metrics Screen width/height:1600/1200 14:32:55 INFO - PROCESS | 15488 | colorDepth:24 14:32:55 INFO - PROCESS | 15488 | Browser inner width/height: 1024/697 14:32:55 INFO - PROCESS | 15488 | __metrics 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/bookmarkbackups = Fri Feb 17 2017 14:32:53 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/extensions.ini = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | Stat failure 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/key3.db = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/thumbnails = Fri Feb 17 2017 14:32:53 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/secmod.db = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/addons.json = Fri Feb 17 2017 14:32:53 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/cookies.sqlite = Fri Feb 17 2017 14:32:54 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/minidumps = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/extensions.json = Fri Feb 17 2017 14:32:53 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/pluginreg.dat = Fri Feb 17 2017 14:32:53 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/startupCache = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/cache2 = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/webappsstore.sqlite = Fri Feb 17 2017 14:32:55 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/frequencyCap.json = Fri Feb 17 2017 14:32:53 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/crashes = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:32:55 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/blocklist.xml = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/cert8.db = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/mimeTypes.rdf = Fri Feb 17 2017 14:32:54 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/user.js = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/content-prefs.sqlite = Fri Feb 17 2017 14:32:53 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/localstore.rdf = Fri Feb 17 2017 14:13:26 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/permissions.sqlite = Fri Feb 17 2017 14:32:52 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/gmp = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/compatibility.ini = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/places.sqlite-wal = Fri Feb 17 2017 14:32:55 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/places.sqlite-shm = Fri Feb 17 2017 14:32:52 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/.parentlock = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/directoryLinks.json = Fri Feb 17 2017 14:32:53 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/extensions = Fri Feb 17 2017 14:32:51 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/prefs.js = Fri Feb 17 2017 14:13:26 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/safebrowsing = Fri Feb 17 2017 14:32:55 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | No birth date. Using mtime. 14:32:55 INFO - PROCESS | 15488 | Using date: /tmp/tmpCMLbCa/profile/places.sqlite = Fri Feb 17 2017 14:32:55 GMT-0800 (PST) 14:32:55 INFO - PROCESS | 15488 | Stat failure 14:32:56 INFO - PROCESS | 15488 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:32:56 INFO - PROCESS | 15488 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:32:57 INFO - TEST-INFO | 15488: exit 0 14:32:57 INFO - Browser initialized. 14:32:57 INFO - Running cycle 1/1 for kraken test... 14:32:57 INFO - TEST-INFO | started process 15579 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCMLbCa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:33:09 INFO - PROCESS | 15579 | RSS: Main: 147066880 14:33:09 INFO - PROCESS | 15579 | 14:33:12 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48477/tests/kraken/driver.html?testName=audio-beat-detection) 14:33:13 INFO - PROCESS | 15579 | RSS: Main: 202260480 14:33:13 INFO - PROCESS | 15579 | 14:33:15 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48477/tests/kraken/driver.html?testName=audio-dft) 14:33:16 INFO - PROCESS | 15579 | RSS: Main: 181084160 14:33:16 INFO - PROCESS | 15579 | 14:33:19 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48477/tests/kraken/driver.html?testName=audio-fft) 14:33:20 INFO - PROCESS | 15579 | RSS: Main: 263839744 14:33:20 INFO - PROCESS | 15579 | 14:33:22 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48477/tests/kraken/driver.html?testName=audio-oscillator) 14:33:22 INFO - PROCESS | 15579 | RSS: Main: 174698496 14:33:22 INFO - PROCESS | 15579 | 14:33:25 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48477/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:33:25 INFO - PROCESS | 15579 | RSS: Main: 177647616 14:33:25 INFO - PROCESS | 15579 | 14:33:28 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48477/tests/kraken/driver.html?testName=imaging-darkroom) 14:33:29 INFO - PROCESS | 15579 | RSS: Main: 217157632 14:33:29 INFO - PROCESS | 15579 | 14:33:32 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48477/tests/kraken/driver.html?testName=imaging-desaturate) 14:33:33 INFO - PROCESS | 15579 | RSS: Main: 211460096 14:33:33 INFO - PROCESS | 15579 | 14:33:36 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48477/tests/kraken/driver.html?testName=json-parse-financial) 14:33:37 INFO - PROCESS | 15579 | RSS: Main: 213098496 14:33:37 INFO - PROCESS | 15579 | 14:33:38 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48477/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:33:39 INFO - PROCESS | 15579 | RSS: Main: 176508928 14:33:39 INFO - PROCESS | 15579 | 14:33:41 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48477/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:33:41 INFO - PROCESS | 15579 | RSS: Main: 184295424 14:33:41 INFO - PROCESS | 15579 | 14:33:44 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48477/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:33:44 INFO - PROCESS | 15579 | RSS: Main: 192954368 14:33:44 INFO - PROCESS | 15579 | 14:33:47 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48477/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:33:48 INFO - PROCESS | 15579 | RSS: Main: 201089024 14:33:48 INFO - PROCESS | 15579 | 14:33:51 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48477/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:33:52 INFO - PROCESS | 15579 | RSS: Main: 198483968 14:33:52 INFO - PROCESS | 15579 | 14:33:53 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:48477/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48477/tests/kraken/driver.html?testName=ai-astar) 14:33:54 INFO - PROCESS | 15579 | RSS: Main: 250847232 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | __start_tp_report 14:33:54 INFO - PROCESS | 15579 | _x_x_mozilla_page_load 14:33:54 INFO - PROCESS | 15579 | _x_x_mozilla_page_load_details 14:33:54 INFO - PROCESS | 15579 | |i|pagename|runs| 14:33:54 INFO - PROCESS | 15579 | |0;ai-astar;108;115;116;115;114;114;115;113;114;116 14:33:54 INFO - PROCESS | 15579 | |1;audio-beat-detection;159;128;126;174;129;130;127;125;162;133 14:33:54 INFO - PROCESS | 15579 | |2;audio-dft;162;161;162;163;162;162;163;165;161;163 14:33:54 INFO - PROCESS | 15579 | |3;audio-fft;78;76;77;103;79;79;78;78;97;78 14:33:54 INFO - PROCESS | 15579 | |4;audio-oscillator;85;85;120;84;83;84;111;84;84;109 14:33:54 INFO - PROCESS | 15579 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;112 14:33:54 INFO - PROCESS | 15579 | |6;imaging-darkroom;149;149;150;150;150;149;148;150;149;149 14:33:54 INFO - PROCESS | 15579 | |7;imaging-desaturate;96;95;95;95;95;95;95;95;95;95 14:33:54 INFO - PROCESS | 15579 | |8;json-parse-financial;63;62;62;62;63;64;62;72;65;63 14:33:54 INFO - PROCESS | 15579 | |9;json-stringify-tinderbox;66;66;66;65;66;66;65;66;65;66 14:33:54 INFO - PROCESS | 15579 | |10;stanford-crypto-aes;88;94;90;88;92;89;89;88;94;88 14:33:54 INFO - PROCESS | 15579 | |11;stanford-crypto-ccm;158;136;145;141;137;135;145;145;182;139 14:33:54 INFO - PROCESS | 15579 | |12;stanford-crypto-pbkdf2;185;185;191;189;190;189;185;186;182;188 14:33:54 INFO - PROCESS | 15579 | |13;stanford-crypto-sha256-iterative;75;70;70;70;71;74;71;70;70;70 14:33:54 INFO - PROCESS | 15579 | __end_tp_report 14:33:54 INFO - PROCESS | 15579 | __start_cc_report 14:33:54 INFO - PROCESS | 15579 | _x_x_mozilla_cycle_collect,2010 14:33:54 INFO - PROCESS | 15579 | __end_cc_report 14:33:54 INFO - PROCESS | 15579 | __startTimestamp1487370834190__endTimestamp 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | ------- Summary: start ------- 14:33:54 INFO - PROCESS | 15579 | Number of tests: 14 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.00 stddev:2.31 (2.0%) stddev-sans-first:1.00 14:33:54 INFO - PROCESS | 15579 | Values: 108.0 115.0 116.0 115.0 114.0 114.0 115.0 113.0 114.0 116.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:131.50 stddev:18.26 (13.9%) stddev-sans-first:17.92 14:33:54 INFO - PROCESS | 15579 | Values: 159.0 128.0 126.0 174.0 129.0 130.0 127.0 125.0 162.0 133.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.50 stddev:1.17 (0.7%) stddev-sans-first:1.24 14:33:54 INFO - PROCESS | 15579 | Values: 162.0 161.0 162.0 163.0 162.0 162.0 163.0 165.0 161.0 163.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#3] audio-fft Cycles:10 Average:82.30 Median:78.00 stddev:9.48 (12.1%) stddev-sans-first:9.92 14:33:54 INFO - PROCESS | 15579 | Values: 78.0 76.0 77.0 103.0 79.0 79.0 78.0 78.0 97.0 78.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:84.00 stddev:14.38 (17.1%) stddev-sans-first:14.96 14:33:54 INFO - PROCESS | 15579 | Values: 85.0 85.0 120.0 84.0 83.0 84.0 111.0 84.0 84.0 109.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:33:54 INFO - PROCESS | 15579 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.50 stddev:0.67 (0.5%) stddev-sans-first:0.71 14:33:54 INFO - PROCESS | 15579 | Values: 149.0 149.0 150.0 150.0 150.0 149.0 148.0 150.0 149.0 149.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 14:33:54 INFO - PROCESS | 15579 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:63.00 stddev:3.05 (4.8%) stddev-sans-first:3.22 14:33:54 INFO - PROCESS | 15579 | Values: 63.0 62.0 62.0 62.0 63.0 64.0 62.0 72.0 65.0 63.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 14:33:54 INFO - PROCESS | 15579 | Values: 66.0 66.0 66.0 65.0 66.0 66.0 65.0 66.0 65.0 66.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:89.50 stddev:2.45 (2.7%) stddev-sans-first:2.49 14:33:54 INFO - PROCESS | 15579 | Values: 88.0 94.0 90.0 88.0 92.0 89.0 89.0 88.0 94.0 88.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:145.00 stddev:14.21 (9.8%) stddev-sans-first:14.43 14:33:54 INFO - PROCESS | 15579 | Values: 158.0 136.0 145.0 141.0 137.0 135.0 145.0 145.0 182.0 139.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.00 Median:188.50 stddev:2.83 (1.5%) stddev-sans-first:2.91 14:33:54 INFO - PROCESS | 15579 | Values: 185.0 185.0 191.0 189.0 190.0 189.0 185.0 186.0 182.0 188.0 14:33:54 INFO - PROCESS | 15579 | 14:33:54 INFO - PROCESS | 15579 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:70.50 stddev:1.85 (2.6%) stddev-sans-first:1.32 14:33:54 INFO - PROCESS | 15579 | Values: 75.0 70.0 70.0 70.0 71.0 74.0 71.0 70.0 70.0 70.0 14:33:54 INFO - PROCESS | 15579 | -------- Summary: end -------- 14:33:54 INFO - PROCESS | 15579 | 14:33:55 INFO - TEST-INFO | 15579: exit 0 14:33:55 INFO - TEST-OK | kraken | took 64318ms 14:33:55 INFO - SUITE-END | took 1188s 14:33:55 INFO - Completed test suite (00:19:48) 14:33:55 INFO - javascript benchmark 14:33:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6235.369505854375, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2560.705518865331, 2569.2806014315993, 2590.8963641454343, 2594.3254753764027, 2594.81836271461, 4655.650826188041, 4773.260144677572, 4790.377250957375, 4803.471618122004, 4814.229723244282, 4761.666683332168, 4790.137775200463, 4802.08760335536, 4804.663673542854, 4857.514248575149, 4428.645708343325, 4440.378347031418, 4456.732596044248, 4492.3710680504755, 4575.359449677031, 4487.371767952489, 4522.796474158671, 4527.32090186473, 4547.909041819162, 4555.407796986387, 4321.200577893069, 4389.978050109761, 4393.07745373472, 4491.842785502525, 4505.189065968125, 4417.138657961698, 4422.933655995163, 4429.069895321986, 4433.13553856998, 4451.287793952984, 4435.268180750205, 4449.310356894686, 4478.104379124188, 4485.70842895214, 4501.414816073922, 4819.831305904277, 4839.975800121012, 4874.46380898101, 4883.974365383274, 4940.3330550375595, 5103.209002604565, 5144.948550514484, 5150.922736158961, 5211.7394130293715, 5219.321488206509, 5182.507661772163, 5196.480351964811, 5197.0, 5200.271961925334, 5209.374875014984, 4792.976035119802, 4825.227963525819, 4860.36815214027, 4863.124637565237, 4872.926906096412, 5329.413764485904, 5371.838844834661, 5373.408925018245, 5378.865528361822, 5383.623146379794, 4887.560119589183, 4927.384076990376, 4939.580135688435, 5053.9241911371355, 5093.23601459784, 5189.325387699575, 5221.373435187802, 5249.265102885599, 5253.23828044939, 5261.894762104736, 5174.741262936838, 5177.119889618773, 5179.533841954242, 5230.0585894539345, 5283.815066472655, 4923.606111511071, 4962.975185124052, 4964.578010869117, 5008.5993120550265, 5018.5232402921665, 4337.479502459725, 4352.477702675699, 4357.0, 4360.084382279693, 4364.8254069837485, 3760.1163726524246, 3772.4529943158086, 3774.867879624255, 3784.5836957934607, 3802.391617341212, 4461.397711308931, 4465.0, 4470.195364834296, 4483.865484035484, 4506.909861802746, 5201.609879258995, 5211.530962213419, 5218.425973142951, 5219.373675159005, 5253.947460525345, 4409.536998615164, 4455.287154055336, 4457.130859482435, 4471.8434854779925, 4474.328850672425, 4803.567678908915, 4805.6155507559315, 4809.254565542276, 4812.06164797861, 4815.374001379868, 4833.613310935187, 4920.901581968341, 4926.926096108489, 4930.6795058321095, 4987.251912213197, 5281.943611277683, 5298.7350632469, 5300.575953923754, 5301.0, 5303.708296043755, 4679.251319788817, 4706.788194531323, 4824.131656301899, 4836.709797412166, 4853.247746599282, 5131.230315452712, 5132.486751324987, 5134.178531434953, 5136.434992150786, 5189.299444574934, 5175.9741201293755, 5188.714620695898, 5188.818391356284, 5195.246689229964, 5239.345081864767, 4922.827701030446, 4973.328600638867, 4975.4278258000795, 5034.84895453137, 5042.09242336383, 4966.751662416937, 5001.299818025406, 5035.92446113316, 5059.519345662156, 5121.231815227746, 4994.825181118644, 4996.675216110941, 4997.9750101249265, 5022.824201152942, 5036.370453693288, 5127.743612819419, 5146.4338922717725, 5152.252923326172, 5152.458991805806, 5203.19350500673, 4780.476157312963, 4891.779869905791, 5002.0, 5012.04771093491, 5014.87462813418], "value": 4748.700861347663, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.4258513730857, 573.5239751006665, 574.0528128587832, 582.5456144207515, 606.4572207873953, 26851.0, 27318.18045458637, 36139.277214455695, 38181.42727859085, 38390.424143637865, 38803.22393552127, 39157.41263881044, 39169.804150979275, 39198.60801391985, 39217.21565568694, 7582.9241707582905, 7778.299953004229, 7779.533228006309, 7789.260020298063, 7792.844143117134, 6778.728850845961, 6941.930580694204, 9378.109079637423, 9617.519124043805, 9630.422174669542, 9639.373440726331, 9676.806463870718, 9690.75773105671, 9704.126628603424, 9715.70852874415, 9746.853797193047, 9795.216382689368, 9804.754881128029, 9810.264230182693, 9814.852777208347, 9567.904320956772, 9623.230141588727, 9628.71113866585, 9629.470379129145, 9640.75898102546, 35603.643963560295, 35689.82155089234, 35780.64219357825, 35805.0, 35833.641663583294, 3210.085125739153, 3226.725728313096, 3229.1281354034513, 3247.52910827931, 3263.379957808009, 26740.46519069608, 26765.86617066902, 26929.73070269292, 27265.86367068172, 28132.578011329846, 3178.952315715266, 3199.168216263765, 3218.535252286495, 3284.2117891706057, 3433.055909624873, 209666.0, 209914.0, 209942.0, 210066.94966525224, 210285.0, 38231.4265286021, 44998.32502512465, 45479.317810232875, 45514.31728524075, 45567.54432455633, 38194.23611527754, 41162.1767564647, 41174.0, 41197.0, 46935.20490287185, 11846.407679616157, 12489.063320251018, 12558.560450384375, 12575.496980120693, 12575.685607859878, 9253.67612133572, 9350.0, 9360.4383736976, 9361.251099911991, 9364.438133712, 12922.353882305848, 13361.599152025456, 15016.47442339513, 15040.32318545668, 15246.46637367709, 6146.170267013986, 6387.361263873576, 6496.7726129585235, 6507.468651646063, 6531.967340163269, 739.3900032473231, 755.6335177438938, 764.8699721047435, 772.5441989226271, 775.1744392222378, 25926.74073259281, 26486.0, 27318.180454586392, 27393.86303068472, 27394.726052739617, 914.2731528434911, 924.3945215883607, 926.6200857648332, 928.7167413938679, 929.3819609959437, 14161.29193540319, 14162.291885405688, 14393.136411815325, 14474.638134046732, 14505.782413263809, 315443.0, 319226.0, 319612.0, 320571.0, 321578.0, 47514.524854751704, 47562.146271223835, 47874.5212547877, 48002.0, 48103.75948120307, 1156.2831044752306, 1200.327816422806, 1200.8558972923306, 1203.602811072344, 1204.7108693913397, 25137.207465685697, 25516.74483255218, 25728.742712572635, 26277.868610656824, 26305.73694263033, 40329.79835100923, 40818.18363632651, 40842.38736419015, 40873.59126408698, 40909.79545102255, 218148.0, 219174.61650767215, 219647.0, 219968.0, 221070.0, 21317.46706332354, 21873.234436794635, 23950.0, 24459.38851528726, 24791.132310369052, 38417.0, 39116.804415977735, 39239.21521569495, 39294.41058384184, 39333.2133357337, 32021.0, 33071.66928330686, 33296.0, 33321.666783332825, 34703.47944780881, 32755.836220819696, 32784.67215327911, 33262.33475330528, 33467.16332091717, 34041.659583403845, 32549.837250813594, 32902.67097328996, 33112.668873310955, 33227.16932076718, 33232.50151247685, 27760.0, 29331.413371732873, 29707.257318567208, 29871.551926720684, 29878.70121298759, 22660.660090098332, 22673.659895101257, 22729.88635056814, 22955.0, 22967.425814354774], "value": 16348.412930834033, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.7166109620657, 375.8815972968516, 381.2223064947506, 396.32426712455265, 419.2223425545613, 12278.938605306972, 12572.30852303123, 12775.50233939221, 17924.0, 17961.550961225978, 17305.39431119911, 17427.390041348524, 17729.55676108098, 17827.197776100074, 17871.463856084338, 5043.974780126095, 5317.255584218213, 5322.361316642008, 5327.30745003151, 5328.280682107924, 5223.582113430916, 5291.206319052154, 5326.760295786679, 5331.520163185312, 5494.972525137368, 5591.133374326986, 5594.188842617818, 5600.1599760035915, 5603.943960560403, 5610.775568977236, 5399.217113518518, 5543.417941116206, 5576.525995290405, 5599.244102046233, 5612.607117501756, 5456.154296084113, 5461.344638643349, 5462.890742185174, 5468.863278418072, 5472.206530053159, 12990.935045324713, 15319.234038298151, 21278.0, 21494.140234390605, 21508.784912150833, 2070.3064473401428, 2110.8100270975688, 2114.3762590035976, 2124.0, 2151.8924053797405, 9313.53432328381, 9351.485668288242, 9357.906420935771, 9840.65557705477, 9928.205743540499, 1636.5663099278581, 1680.1935071165908, 1696.8388003139682, 1700.9744853827203, 1710.7262837945868, 70963.29036709618, 73335.0, 73669.63165184193, 75294.62352688202, 75451.62274188649, 10795.244332896778, 10796.136309095253, 10936.367770598148, 10942.0, 10991.890081099247, 15233.542993710207, 15272.31274592646, 15281.38874445032, 15351.15568643713, 15385.153816539974, 6724.462043036546, 6855.794326170223, 7014.088168538057, 7248.0, 7306.561606303639, 6367.745290188341, 6369.9681501592195, 6544.181977252843, 6554.573952693059, 6586.901196482057, 4086.7956602169775, 4278.251306021434, 4309.806058727365, 4619.445666520039, 4655.930161047587, 1816.1464111867401, 1826.214727667116, 1856.4430670798977, 1916.3292847503542, 1967.950801229952, 785.2696991797683, 789.163486704084, 796.9442139050211, 799.4683535448861, 809.7246936041774, 17045.659086818196, 17715.73426398605, 17771.289148433916, 17924.820751792577, 18164.18261178224, 791.1297572670132, 792.2038351456749, 795.2167115391335, 800.795797071743, 804.6057431858347, 9340.906590934139, 9363.25093992479, 9618.855717164248, 9650.179734722487, 9897.37719504332, 470106.0, 473489.0, 496387.0, 502065.0, 504420.0, 83767.5811620938, 86109.56945215234, 86337.13662863417, 86729.5663521691, 86808.5659571698, 914.2411798207472, 922.9861552076724, 952.1620973543238, 961.7162936933621, 972.7081875437341, 15805.288762005508, 15844.76232856485, 16577.50267491977, 16580.502584922473, 16843.326266949185, 36999.26001480009, 37460.625393745715, 37926.62073379231, 38436.0, 38813.0, 47673.52326476691, 49750.75124624354, 50076.0, 50573.49426505688, 53437.73281133569, 7639.121501027453, 7759.262870027338, 7821.687132514636, 7914.762557123296, 8260.83478330442, 8803.823923521622, 11585.942070289595, 11691.766164676828, 12330.876691232972, 12432.564860229848, 9976.05227503386, 10095.949520252352, 10377.325473844176, 10497.265191436527, 10798.136149107895, 10210.744731381776, 10349.74125646865, 10406.271560990961, 10435.94782026085, 10646.0, 10097.293189476666, 10666.306690065432, 10689.94655026751, 10693.876330880386, 10998.835017474585, 7536.434767392358, 7590.734324298623, 7774.067111946602, 7823.765287041398, 7977.960110199412, 8339.54132522717, 9966.0, 10070.04334588213, 10289.434081125311, 10328.638497652546], "value": 8338.406649202192, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1193.8089905615104, 1302.2446980751165, 1331.4607583928512, 1337.9866201337986, 1343.9731205375892, 6165.907511387327, 7245.0, 7478.046171762157, 7479.878018297244, 7718.807029824243, 7544.622768861563, 7623.504472209303, 7623.733169339061, 7625.656845441955, 7628.847423051536, 2009.537806304547, 2045.0694933805082, 2048.1909645689943, 2048.4878780304925, 2049.3646969439446, 1762.3743571032292, 1811.0401487211734, 1991.163711241282, 2040.8877511736848, 2044.4071219346372, 1998.1208268361947, 2000.4398768344886, 2001.5996800639857, 2003.7795842457317, 2009.377093101136, 1953.082051435823, 1968.9310874119408, 1973.4079776067122, 1978.1889425335687, 1980.1584326661255, 1969.094216660338, 2023.6053969475959, 2041.7141600175987, 2044.63196624609, 2046.0, 7391.630418479054, 7462.0, 7464.2535746425465, 7468.439867009996, 7477.7008919643695, 4219.324908014733, 4321.135772845443, 4330.112326972994, 4331.826726930919, 4336.154449882274, 2608.2044976282227, 2703.4863375958703, 2748.8833598129327, 2750.037486879596, 2755.3111722069484, 1040.9011143941314, 1063.7074804428767, 1064.6060957445718, 1066.1151244467073, 1082.7888561730465, 9212.677556285566, 9499.43003419797, 9566.378185417925, 9683.273754468442, 9726.17327527161, 3639.890803275906, 3693.778373297611, 3773.3019391412677, 3777.792221427848, 3820.789856557916, 7749.496282741604, 7758.0, 7762.728304509315, 7770.067591889009, 7779.45543811939, 4377.824887004548, 4378.211921854033, 4380.080183161572, 4385.407969924019, 4386.758728269976, 3878.127421330178, 3990.1221731219084, 4069.6337329640473, 4072.3077076897, 4077.673786097105, 3210.6307774605752, 3233.0785726067957, 3303.50447432887, 3307.8676852926096, 3309.933801324008, 981.5779214937645, 984.2814745236018, 986.4032260482448, 997.436448406648, 1001.4492029383955, 686.0, 703.8134894253027, 716.8781307177792, 723.3453724379445, 740.167311774254, 4649.837255696035, 4692.765361731968, 4805.13507568634, 4892.877678058077, 4903.583195428429, 512.2648998686897, 518.7121147763028, 527.6649327676953, 530.7823792245161, 539.6842846934587, 3214.1803840020643, 3284.080457471912, 3595.7842529448317, 3743.082944678571, 3756.6806821419937, 8685.6525738971, 8866.512341821262, 8867.91132088671, 8871.512066836256, 9028.638854445748, 8724.825503489894, 8798.428102173339, 8808.20726134651, 8825.823483530294, 8853.60158792843, 6783.355581219875, 6937.537174303151, 6943.791686249521, 6947.617881016491, 6950.860982780316, 5040.269160971712, 5110.565601923878, 5125.666831655931, 5148.172398798372, 5227.660202086852, 7877.803054923673, 7904.723334683258, 7947.364210863001, 8027.317677997202, 8077.192280771876, 7954.204579542, 8198.590070496331, 8265.380096492907, 8269.09040005611, 8269.751907442786, 6380.553361264852, 6530.216374035146, 6585.176852893388, 6587.143671322698, 6593.670316484061, 7044.260352662896, 7094.787156385317, 7103.396211322096, 7107.431405487445, 7115.288471152843, 6240.875182496416, 6332.778352757631, 6383.329750376143, 6384.712687929147, 6550.148480697479, 6335.366463353628, 6371.426571608577, 6385.393387628168, 6386.3395517164945, 6390.840228994312, 5433.4566543345345, 5460.153676180198, 5512.944870551403, 5523.917141242965, 5525.171224316385, 4462.352958820888, 4464.17412778637, 4487.259602165736, 4490.57339552742, 4521.97739011316, 4454.7549884756645, 4474.955250447454, 4495.348174514743, 4574.954250457586, 4706.247000479907], "value": 3807.0544328398364, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.33741731435015, 324.71262932304916, 347.8747650845696, 349.50934265358245, 355.60527814126317, 4711.552402521764, 4841.201201801703, 4884.853454396365, 4902.852914412573, 4913.852584422473, 4684.906301873961, 4826.179549476588, 4879.487653796354, 4886.071646387193, 4911.361523002014, 3856.1709232515072, 3932.488776459056, 3946.171304026158, 3959.841606335743, 3971.2256110058406, 3706.610805865372, 3779.8677046303383, 3806.3338915689783, 3975.807257822656, 3981.2236613860164, 3862.0151861275476, 3871.477350557667, 3894.5727759571537, 3908.237893610747, 3915.158240978189, 3854.2869569129803, 3864.555576108727, 3879.35990561557, 3889.7860617666015, 3928.292907276689, 3291.9835400822844, 3379.746519011084, 3511.1924257420937, 3520.102373894666, 3535.699465545432, 4511.639068874482, 4791.832285869978, 4795.105933156418, 4810.6151507879285, 4819.638527110481, 4492.7528985905765, 4509.503954564995, 4510.849733318016, 4519.254323036695, 4526.2984237442915, 4913.31213630092, 5174.767135478913, 5195.298634684307, 5201.347312870425, 5212.556932660728, 3892.6885849132286, 3895.668868146211, 3896.7204185551204, 3913.4716813230134, 3928.9214215715524, 5369.778133120136, 5392.78428862845, 5403.243545903577, 5410.296661434028, 5429.728513574384, 3997.720159588801, 4014.6788256939376, 4018.598140185958, 4035.1929614077303, 4037.313656678372, 3911.941320880189, 3965.78188199646, 3990.022444501116, 3998.9400158997637, 3999.1201935574127, 4144.48193975753, 4216.620504154641, 4253.723507971972, 4356.6732495063, 4432.58690808076, 5081.7967281309075, 5128.153854613947, 5147.948520514822, 5150.510701483353, 5171.5862730981435, 1355.9254241016838, 1358.3208395802098, 1366.4670778396433, 1375.0580852116332, 1377.4008306386554, 940.9012053734259, 970.1317320997667, 986.284943416032, 993.4188499727596, 1005.772956992468, 4357.455318085239, 4359.689364636748, 4392.3191905254735, 4414.977925110354, 4427.512973572904, 5105.948940510547, 5132.3327967364, 5138.715321169708, 5161.561267292323, 5168.3539557555305, 4248.851290204828, 4456.5043575597765, 4470.508244093147, 4473.0, 4573.428321459817, 2715.416185520083, 2718.4427192425505, 2719.551274039801, 2735.193118030186, 2743.3690251242324, 4854.0, 4867.026594681078, 4878.292647566083, 4888.7311197884455, 4892.437369702529, 4994.675346102492, 5216.209328009572, 5272.260154149121, 5276.762545685453, 5299.682019078868, 4850.83022094225, 4855.660103792771, 4866.829660961991, 4870.245112007645, 4879.170541007966, 5123.231515272739, 5139.563137133386, 5151.0, 5182.455842136672, 5186.351706036746, 4893.241547560134, 5040.672356296829, 5045.974770126126, 5052.311812377059, 5092.923606145837, 4815.374001379938, 4833.758312084311, 4835.443923948791, 4842.757862106951, 4855.854324370274, 3610.4403817408343, 3618.2401695644207, 3626.546681664792, 3638.072291565634, 3650.8174591270863, 4960.553550180501, 5070.0, 5095.464976177596, 5129.8717532062, 5166.793328266827, 4947.752612369295, 4949.455559888482, 4964.180910149929, 4993.076280888047, 5009.77456014473, 4997.75056235941, 5001.249063202598, 5019.774110164978, 5023.8492845214705, 5033.974830125826, 4731.683067968496, 4862.489438609036, 4870.659053866337, 4939.5554400104165, 4977.477364876636, 4997.5002499749735, 4997.775100120431, 5000.849804544902, 5009.624278179078, 5013.749312534285, 4816.95183048165, 4916.050369778104, 4923.986950635221, 4928.926066108939, 4940.851774446773], "value": 3879.9209824508525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.1822721598003, 660.5112216959451, 673.1350214973759, 688.7933619914027, 706.8727629026777, 12454.564090256843, 13476.056676032682, 13494.122882012662, 13520.729585408286, 13586.456541738331, 9481.52592370382, 12306.56927007555, 12404.069694772883, 12437.191582547128, 12437.626871193856, 599.9010163323057, 790.7746292306693, 810.027966440271, 817.1052697296481, 818.6807145213371, 816.2694388522269, 817.0440584516131, 818.6193420059672, 818.97133600324, 819.2012787532168, 858.0304256190497, 858.0518527027638, 858.1761508951414, 858.4720396955859, 858.7981824271292, 849.3714651158099, 854.2824027816602, 855.7218903856209, 858.7938894665298, 859.2138193552892, 626.3830127324582, 630.0, 631.2929518938772, 631.4979591224987, 631.561065059779, 13501.797473037814, 13560.864391356059, 13575.52485662997, 13609.591712248648, 13634.0, 11109.555617775279, 12073.275603463733, 12159.209651372632, 13031.86968130316, 13044.0, 10120.240981926312, 10203.33678310915, 10307.433091179983, 10332.690019299358, 10702.625408110678, 3142.889998850052, 3158.3209609933974, 3289.8190599517015, 3322.401967645823, 3328.0681409205463, 63101.0, 63837.0, 64270.357296426904, 64979.0, 65943.0, 9651.469169195761, 9674.274429417821, 9783.26625503076, 9787.510624468749, 10151.543180556893, 17626.64746705059, 17783.19975601101, 17818.732719009226, 17892.552686182687, 18255.452336429928, 5933.0, 5988.3113441953865, 6060.2424696912885, 6098.847528811815, 6121.693915304217, 3475.1138459692615, 3505.94741078884, 3526.2947410517486, 3560.323538527723, 3589.335972845032, 2819.873105710248, 2890.783191260664, 2903.6951120132503, 2907.592936988823, 2920.240737408247, 473.40587562608704, 479.4199019186823, 482.78998635593626, 489.64500736966124, 493.2305603258928, 598.6976576828673, 623.7692053940071, 623.9968800155971, 639.7888696730068, 653.9542232043807, 7932.0, 8100.432969692183, 8377.0, 8586.356023298278, 8745.737627871174, 588.4791959116127, 614.4961131871822, 621.4251817069193, 623.4669357699192, 624.6064979063252, 3777.0, 3781.149241420713, 3995.380715989026, 4453.599176074169, 4707.458642256116, 49378.75310623424, 61319.0, 61576.69211653913, 63632.681836591444, 63660.68169659122, 48416.27375589369, 51040.0, 51301.74349128231, 52682.20976685353, 53347.19979200315, 46889.765551172706, 47451.525484744714, 47822.76088619535, 48209.0, 49693.75153124211, 8552.315814734931, 8885.42244754089, 8921.955390223007, 9079.636814527345, 9175.954120229357, 33338.0, 33905.66094339025, 34142.487862681584, 34241.0, 35446.822765886005, 39758.0, 40108.0, 42281.0403271768, 43312.350314745934, 44324.33513497236, 10803.729906752395, 11049.668509944715, 11148.832767508657, 11258.66224013281, 11317.490712918103, 12253.203541769757, 12270.386480675752, 12282.75434491323, 12447.751044979232, 13116.606501804961, 10790.67627971162, 10861.511231994737, 10923.0, 11131.888681113085, 11415.714607134889, 11120.388378639253, 11327.886721133013, 11328.433578320886, 11591.072714182677, 11746.823797643214, 10700.464976750976, 10722.142228621535, 10885.782284354427, 10950.507227174638, 11049.779004420026, 8876.822463550563, 9003.54982250898, 9050.0, 9251.629934802533, 9536.380135291183, 8780.209781119474, 9587.520623968912, 9624.0, 9664.602605814198, 9848.753781155528], "value": 6145.806120334027, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1572.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 116.0, 115.0, 114.0, 114.0, 115.0, 113.0, 114.0, 116.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 128.0, 126.0, 174.0, 129.0, 130.0, 127.0, 125.0, 162.0, 133.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 163.0, 162.0, 162.0, 163.0, 165.0, 161.0, 163.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 77.0, 103.0, 79.0, 79.0, 78.0, 78.0, 97.0, 78.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 120.0, 84.0, 83.0, 84.0, 111.0, 84.0, 84.0, 109.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 150.0, 150.0, 149.0, 148.0, 150.0, 149.0, 149.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 63.0, 64.0, 62.0, 72.0, 65.0, 63.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 90.0, 88.0, 92.0, 89.0, 89.0, 88.0, 94.0, 88.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 136.0, 145.0, 141.0, 137.0, 135.0, 145.0, 145.0, 182.0, 139.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 191.0, 189.0, 190.0, 189.0, 185.0, 186.0, 182.0, 188.0], "value": 187.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 70.0, 70.0, 70.0, 71.0, 74.0, 71.0, 70.0, 70.0, 70.0], "value": 71.1, "unit": "score"}]}]} 14:33:55 INFO - Return code: 0 14:33:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:33:56 ERROR - # TBPL SUCCESS # 14:33:56 INFO - Running post-action listener: _resource_record_post_action 14:33:56 INFO - [mozharness: 2017-02-17 22:33:56.010710Z] Finished run-tests step (success) 14:33:56 INFO - Running post-run listener: _resource_record_post_run 14:33:56 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 45056; Write bytes: 360792064; Read time: 56; Write time: 190836 14:33:56 INFO - TinderboxPrint: CPU usage
13.2% 14:33:56 INFO - TinderboxPrint: I/O read bytes / time
45,056 / 56 14:33:56 INFO - TinderboxPrint: I/O write bytes / time
360,792,064 / 190,836 14:33:56 INFO - TinderboxPrint: CPU idle
8,341.9 (86.8%) 14:33:56 INFO - TinderboxPrint: CPU user
1,223.1 (12.7%) 14:33:56 INFO - TinderboxPrint: Swap in / out
0 / 0 14:33:56 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105480192; Read time: 0; Write time: 81312 14:33:56 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 45056; Write bytes: 255311872; Read time: 56; Write time: 109524 14:33:56 INFO - Running post-run listener: _upload_blobber_files 14:33:56 INFO - Blob upload gear active. 14:33:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:33:56 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 14:33:56 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'] 14:33:56 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 14:33:57 INFO - (blobuploader) - INFO - Open directory for files ... 14:33:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:33:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:33:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:57 INFO - (blobuploader) - INFO - Done attempting. 14:33:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:33:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:33:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:33:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:33:58 INFO - (blobuploader) - INFO - Done attempting. 14:33:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:33:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:33:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:34:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:34:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:34:02 INFO - (blobuploader) - INFO - Done attempting. 14:34:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:34:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:34:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:34:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:34:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:34:03 INFO - (blobuploader) - INFO - Done attempting. 14:34:03 INFO - (blobuploader) - INFO - Iteration through files over. 14:34:03 INFO - Return code: 0 14:34:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:34:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:34:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1bb6a8dfa458544b3df3531e48ff07bb973ce460d1dd2f456ecdf3cb67f9b5f488b72e0b333356e9c9542e5d3d02a2a6610809c8a0f910da908370fe001dc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef93513be8099aa1414e1399ac637d735db2d15978403fd5081c0da8bd05d15cc19b1a64c108d6ca151b2c6c9332125134a9e86a9760ce242611cc026c8cfc6a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e04a9e4da89223bebe4babad6f189e19d2fa695e1aed3f381a68b2ba612f3bcccbec5766e6fc8da3445977ff6e72f4013db0315f26aeda64f373f2ddeaaec8e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1ac7a6eab221feadbc94bfbd6f9b0735903ac818857f0e1a1b6632c4935daf9a55be5701f458af9c9f2cae0effaa1477008aa9a7d9f72d9a3a860e6e82af4fc"} 14:34:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:34:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:34:03 INFO - Contents: 14:34:03 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1bb6a8dfa458544b3df3531e48ff07bb973ce460d1dd2f456ecdf3cb67f9b5f488b72e0b333356e9c9542e5d3d02a2a6610809c8a0f910da908370fe001dc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef93513be8099aa1414e1399ac637d735db2d15978403fd5081c0da8bd05d15cc19b1a64c108d6ca151b2c6c9332125134a9e86a9760ce242611cc026c8cfc6a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e04a9e4da89223bebe4babad6f189e19d2fa695e1aed3f381a68b2ba612f3bcccbec5766e6fc8da3445977ff6e72f4013db0315f26aeda64f373f2ddeaaec8e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1ac7a6eab221feadbc94bfbd6f9b0735903ac818857f0e1a1b6632c4935daf9a55be5701f458af9c9f2cae0effaa1477008aa9a7d9f72d9a3a860e6e82af4fc"} 14:34:03 INFO - Running post-run listener: copy_logs_to_upload_dir 14:34:03 INFO - Copying logs to upload dir... 14:34:03 INFO - mkdir: /builds/slave/test/build/upload/logs 14:34:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.516525 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-17 14:34:03.006747) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 14:34:03.010801) ========= 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=6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1bb6a8dfa458544b3df3531e48ff07bb973ce460d1dd2f456ecdf3cb67f9b5f488b72e0b333356e9c9542e5d3d02a2a6610809c8a0f910da908370fe001dc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef93513be8099aa1414e1399ac637d735db2d15978403fd5081c0da8bd05d15cc19b1a64c108d6ca151b2c6c9332125134a9e86a9760ce242611cc026c8cfc6a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e04a9e4da89223bebe4babad6f189e19d2fa695e1aed3f381a68b2ba612f3bcccbec5766e6fc8da3445977ff6e72f4013db0315f26aeda64f373f2ddeaaec8e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1ac7a6eab221feadbc94bfbd6f9b0735903ac818857f0e1a1b6632c4935daf9a55be5701f458af9c9f2cae0effaa1477008aa9a7d9f72d9a3a860e6e82af4fc"} build_url:https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011778 build_url: 'https://queue.taskcluster.net/v1/task/MobKWfk2RVS1PNGQwQj7zg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f1bb6a8dfa458544b3df3531e48ff07bb973ce460d1dd2f456ecdf3cb67f9b5f488b72e0b333356e9c9542e5d3d02a2a6610809c8a0f910da908370fe001dc59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ef93513be8099aa1414e1399ac637d735db2d15978403fd5081c0da8bd05d15cc19b1a64c108d6ca151b2c6c9332125134a9e86a9760ce242611cc026c8cfc6a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e04a9e4da89223bebe4babad6f189e19d2fa695e1aed3f381a68b2ba612f3bcccbec5766e6fc8da3445977ff6e72f4013db0315f26aeda64f373f2ddeaaec8e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e1ac7a6eab221feadbc94bfbd6f9b0735903ac818857f0e1a1b6632c4935daf9a55be5701f458af9c9f2cae0effaa1477008aa9a7d9f72d9a3a860e6e82af4fc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 14:34:03.035863) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:34:03.036179) ========= 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=6dbc10da6d83fee962c624be000001a8-1487369578.835533-799167393 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004561 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:34:03.118319) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 14:34:03.118658) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 14:34:03.118964) ========= ========= Total master_lag: 0.16 =========